builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1473188226.43 results: success (0) buildid: 20160906094754 builduid: 91240e828c4b4db382a5c6bf86e9b40e revision: 236f750b6a3002e91e551fd6c2ddec63995a6e0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:06.434681) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:06.435149) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:06.435431) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:06.525394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:06.525689) ========= 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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-06 11:57:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2016-09-06 11:57:07 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.542738 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:07.101908) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:07.102204) ========= 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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.030738 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:07.154123) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:07.154481) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 236f750b6a3002e91e551fd6c2ddec63995a6e0e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 236f750b6a3002e91e551fd6c2ddec63995a6e0e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-06 11:57:07,227 truncating revision to first 12 chars 2016-09-06 11:57:07,227 Setting DEBUG logging. 2016-09-06 11:57:07,227 attempt 1/10 2016-09-06 11:57:07,227 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/236f750b6a30?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 11:57:07,909 unpacking tar archive at: larch-236f750b6a30/testing/mozharness/ program finished with exit code 0 elapsedTime=0.943830 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:08.121152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:08.121477) ========= script_repo_revision: 236f750b6a3002e91e551fd6c2ddec63995a6e0e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:08.121966) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:08.122275) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 11:57:08.141186) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-06 11:57:08.141556) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:57:08 INFO - MultiFileLogger online at 20160906 11:57:08 in /builds/slave/test 11:57:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 11:57:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:57:08 INFO - {'append_to_log': False, 11:57:08 INFO - 'base_work_dir': '/builds/slave/test', 11:57:08 INFO - 'blob_upload_branch': 'Larch', 11:57:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:57:08 INFO - 'branch': 'Larch', 11:57:08 INFO - 'buildbot_json_path': 'buildprops.json', 11:57:08 INFO - 'config_files': ('talos/mac_config.py',), 11:57:08 INFO - 'default_actions': ('clobber', 11:57:08 INFO - 'read-buildbot-config', 11:57:08 INFO - 'download-and-extract', 11:57:08 INFO - 'populate-webroot', 11:57:08 INFO - 'create-virtualenv', 11:57:08 INFO - 'install', 11:57:08 INFO - 'run-tests'), 11:57:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:57:08 INFO - 'download_minidump_stackwalk': True, 11:57:08 INFO - 'download_symbols': 'ondemand', 11:57:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:57:08 INFO - 'tooltool.py': '/tools/tooltool.py', 11:57:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:57:08 INFO - '/tools/misc-python/virtualenv.py')}, 11:57:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:57:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:57:08 INFO - 'installer_path': 'installer.exe', 11:57:08 INFO - 'log_level': 'info', 11:57:08 INFO - 'log_name': 'talos', 11:57:08 INFO - 'log_to_console': True, 11:57:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:57:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:57:08 INFO - 'opt_config_files': (), 11:57:08 INFO - 'pip_index': False, 11:57:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:57:08 INFO - 'cmd': ('bash', 11:57:08 INFO - '-c', 11:57:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:57:08 INFO - 'enabled': True, 11:57:08 INFO - 'halt_on_failure': False, 11:57:08 INFO - 'name': 'check_screen_resolution'},), 11:57:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:57:08 INFO - 'cmd': ('bash', 11:57:08 INFO - '-c', 11:57:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:57:08 INFO - 'enabled': True, 11:57:08 INFO - 'halt_on_failure': False, 11:57:08 INFO - 'name': 'check_screen_resolution'},), 11:57:08 INFO - 'run_cmd_checks_enabled': True, 11:57:08 INFO - 'sps_profile': False, 11:57:08 INFO - 'sps_profile_interval': 0, 11:57:08 INFO - 'suite': 'dromaeojs', 11:57:08 INFO - 'system_bits': '32', 11:57:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:57:08 INFO - 'title': 't-yosemite-r7-0132', 11:57:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:57:08 INFO - 'use_talos_json': True, 11:57:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:57:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:57:08 INFO - 'work_dir': 'build'} 11:57:08 INFO - [mozharness: 2016-09-06 18:57:08.334333Z] Running clobber step. 11:57:08 INFO - Running pre-action listener: _resource_record_pre_action 11:57:08 INFO - Running main action method: clobber 11:57:08 INFO - rmtree: /builds/slave/test/build 11:57:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:57:11 INFO - Running post-action listener: _resource_record_post_action 11:57:11 INFO - [mozharness: 2016-09-06 18:57:11.008375Z] Finished clobber step (success) 11:57:11 INFO - [mozharness: 2016-09-06 18:57:11.008504Z] Running read-buildbot-config step. 11:57:11 INFO - Running pre-action listener: _resource_record_pre_action 11:57:11 INFO - Running main action method: read_buildbot_config 11:57:11 INFO - Using buildbot properties: 11:57:11 INFO - { 11:57:11 INFO - "project": "", 11:57:11 INFO - "product": "firefox", 11:57:11 INFO - "script_repo_revision": "production", 11:57:11 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 11:57:11 INFO - "repository": "", 11:57:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 11:57:11 INFO - "buildid": "20160906094754", 11:57:11 INFO - "pgo_build": "False", 11:57:11 INFO - "basedir": "/builds/slave/test", 11:57:11 INFO - "buildnumber": 0, 11:57:11 INFO - "slavename": "t-yosemite-r7-0132", 11:57:11 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 11:57:11 INFO - "platform": "yosemite_r7", 11:57:11 INFO - "scheduler": "tests-larch-macosx64-talos", 11:57:11 INFO - "branch": "larch", 11:57:11 INFO - "repo_path": "projects/larch", 11:57:11 INFO - "revision": "236f750b6a3002e91e551fd6c2ddec63995a6e0e", 11:57:11 INFO - "stage_platform": "macosx64", 11:57:11 INFO - "builduid": "91240e828c4b4db382a5c6bf86e9b40e", 11:57:11 INFO - "slavebuilddir": "test" 11:57:11 INFO - } 11:57:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:57:11 INFO - Running post-action listener: _resource_record_post_action 11:57:11 INFO - [mozharness: 2016-09-06 18:57:11.011997Z] Finished read-buildbot-config step (success) 11:57:11 INFO - [mozharness: 2016-09-06 18:57:11.012132Z] Running download-and-extract step. 11:57:11 INFO - Running pre-action listener: _resource_record_pre_action 11:57:11 INFO - Running main action method: download_and_extract 11:57:11 INFO - mkdir: /builds/slave/test/build/tests 11:57: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')]} 11:57:11 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:57:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:57:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 11:57:11 INFO - Downloaded 1199 bytes. 11:57:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:57:11 INFO - Using the following test package requirements: 11:57:11 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:57:11 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:11 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:57:11 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:11 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:57:11 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:11 INFO - u'jsshell-mac.zip'], 11:57:11 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:11 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:57:11 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:57:11 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:11 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:57:11 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:11 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:57:11 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:11 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:57:11 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:57:11 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:57:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:57: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')]} 11:57:11 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:57:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:57:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:57:12 INFO - Downloaded 16369902 bytes. 11:57:12 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:57:20 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:20 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:57:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:57:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:57:20 INFO - Downloaded 16369902 bytes. 11:57:20 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:27 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:57:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:57:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:57:28 INFO - Downloaded 14413998 bytes. 11:57:28 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:28 INFO - trying https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:57:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:57:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 11:57:31 INFO - Downloaded 111605229 bytes. 11:57:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:57:31 INFO - mkdir: /builds/slave/test/properties 11:57:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:57:31 INFO - Writing to file /builds/slave/test/properties/build_url 11:57:31 INFO - Contents: 11:57:31 INFO - build_url:https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:57:32 INFO - Running post-action listener: _resource_record_post_action 11:57:32 INFO - Running post-action listener: set_extra_try_arguments 11:57:32 INFO - [mozharness: 2016-09-06 18:57:32.602300Z] Finished download-and-extract step (success) 11:57:32 INFO - [mozharness: 2016-09-06 18:57:32.602434Z] Running populate-webroot step. 11:57:32 INFO - Running pre-action listener: _resource_record_pre_action 11:57:32 INFO - Running main action method: populate_webroot 11:57:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:57:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:57:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:57:32 INFO - '--tppagecycles', 11:57:32 INFO - '7'], 11:57:32 INFO - 'tests': ['tsvgm']}}, 11:57:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:57:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:57:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:57:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:57:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:32 INFO - 'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:32 INFO - 'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['damp', 'tps']}, 11:57:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:32 INFO - 'tests': ['damp', 'tps']}, 11:57:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['dromaeo_dom']}, 11:57:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:57:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['basic_compositor_video']}, 11:57:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:57:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['a11yr', 11:57:32 INFO - 'ts_paint', 11:57:32 INFO - 'tpaint', 11:57:32 INFO - 'sessionrestore', 11:57:32 INFO - 'sessionrestore_no_auto_restore', 11:57:32 INFO - 'tabpaint']}, 11:57:32 INFO - 'other-e10s': {'tests': ['a11yr', 11:57:32 INFO - 'ts_paint', 11:57:32 INFO - 'tpaint', 11:57:32 INFO - 'sessionrestore', 11:57:32 INFO - 'sessionrestore_no_auto_restore', 11:57:32 INFO - 'tabpaint']}, 11:57:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:57:32 INFO - 'ts_paint', 11:57:32 INFO - 'tpaint', 11:57:32 INFO - 'sessionrestore', 11:57:32 INFO - 'sessionrestore_no_auto_restore', 11:57:32 INFO - 'tabpaint']}, 11:57:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:57:32 INFO - 'ts_paint', 11:57:32 INFO - 'tpaint', 11:57:32 INFO - 'sessionrestore', 11:57:32 INFO - 'sessionrestore_no_auto_restore', 11:57:32 INFO - 'tabpaint']}, 11:57:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['a11yr', 11:57:32 INFO - 'ts_paint', 11:57:32 INFO - 'tpaint', 11:57:32 INFO - 'sessionrestore', 11:57:32 INFO - 'sessionrestore_no_auto_restore', 11:57:32 INFO - 'tabpaint']}, 11:57:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['a11yr', 11:57:32 INFO - 'ts_paint', 11:57:32 INFO - 'tpaint', 11:57:32 INFO - 'sessionrestore', 11:57:32 INFO - 'sessionrestore_no_auto_restore', 11:57:32 INFO - 'tabpaint']}, 11:57:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['tsvgx', 11:57:32 INFO - 'tsvgr_opacity', 11:57:32 INFO - 'tart', 11:57:32 INFO - 'tscrollx', 11:57:32 INFO - 'cart']}, 11:57:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:57:32 INFO - 'tsvgr_opacity', 11:57:32 INFO - 'tart', 11:57:32 INFO - 'tscrollx', 11:57:32 INFO - 'cart']}, 11:57:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:32 INFO - 'talos_options': ['--disable-e10s'], 11:57:32 INFO - 'tests': ['tp5o']}, 11:57:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:32 INFO - 'tests': ['tp5o']}, 11:57:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:32 INFO - 'talos_options': ['--disable-e10s', 11:57:32 INFO - '--xperf_path', 11:57:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:32 INFO - 'tests': ['tp5n']}, 11:57:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:57:32 INFO - 'talos_options': ['--xperf_path', 11:57:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:32 INFO - 'tests': ['tp5n']}}, 11:57:32 INFO - 'talos.zip': {'path': '', 11:57:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:57:32 INFO - Running post-action listener: _resource_record_post_action 11:57:32 INFO - [mozharness: 2016-09-06 18:57:32.620876Z] Finished populate-webroot step (success) 11:57:32 INFO - [mozharness: 2016-09-06 18:57:32.621015Z] Running create-virtualenv step. 11:57:32 INFO - Running pre-action listener: _resource_record_pre_action 11:57:32 INFO - Running main action method: create_virtualenv 11:57:32 INFO - Creating virtualenv /builds/slave/test/build/venv 11:57:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:57:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:57:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:57:32 INFO - Using real prefix '/tools/python27' 11:57:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:57:33 INFO - Installing distribute.............................................................................................................................................................................................done. 11:57:36 INFO - Installing pip.................done. 11:57:36 INFO - Return code: 0 11:57:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:57: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', 'pip>=1.5'] in /builds/slave/test/build 11:57: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 pip>=1.5 11:57:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:57:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:36 INFO - 'HOME': '/Users/cltbld', 11:57:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:36 INFO - 'LOGNAME': 'cltbld', 11:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:36 INFO - 'MOZ_NO_REMOTE': '1', 11:57:36 INFO - 'NO_EM_RESTART': '1', 11:57:36 INFO - 'PAGER': '/bin/cat', 11:57:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:36 INFO - 'PWD': '/builds/slave/test', 11:57:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:36 INFO - 'SHELL': '/bin/bash', 11:57:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:57:36 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:57:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:36 INFO - 'USER': 'cltbld', 11:57:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:36 INFO - 'XPC_FLAGS': '0x0', 11:57:36 INFO - 'XPC_SERVICE_NAME': '0', 11:57:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:57:36 INFO - Cleaning up... 11:57:36 INFO - Return code: 0 11:57:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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', '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 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:57: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:57: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 psutil>=3.1.1 11:57:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:57:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:36 INFO - 'HOME': '/Users/cltbld', 11:57:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:36 INFO - 'LOGNAME': 'cltbld', 11:57:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:36 INFO - 'MOZ_NO_REMOTE': '1', 11:57:36 INFO - 'NO_EM_RESTART': '1', 11:57:36 INFO - 'PAGER': '/bin/cat', 11:57:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:36 INFO - 'PWD': '/builds/slave/test', 11:57:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:36 INFO - 'SHELL': '/bin/bash', 11:57:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:57:36 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:57:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:36 INFO - 'USER': 'cltbld', 11:57:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:36 INFO - 'XPC_FLAGS': '0x0', 11:57:36 INFO - 'XPC_SERVICE_NAME': '0', 11:57:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:36 INFO - Downloading/unpacking psutil>=3.1.1 11:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:57:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:40 INFO - Installing collected packages: psutil 11:57:40 INFO - Running setup.py install for psutil 11:57:40 INFO - building 'psutil._psutil_osx' extension 11:57:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:57:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:57:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:57:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:57:41 INFO - building 'psutil._psutil_posix' extension 11:57:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:57:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:57:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:57:41 INFO - ^ 11:57:41 INFO - 1 warning generated. 11:57:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:57:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:41 INFO - Successfully installed psutil 11:57:41 INFO - Cleaning up... 11:57:41 INFO - Return code: 0 11:57:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:41 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 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:57:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:57:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:57:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:41 INFO - 'HOME': '/Users/cltbld', 11:57:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:41 INFO - 'LOGNAME': 'cltbld', 11:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:41 INFO - 'MOZ_NO_REMOTE': '1', 11:57:41 INFO - 'NO_EM_RESTART': '1', 11:57:41 INFO - 'PAGER': '/bin/cat', 11:57:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:41 INFO - 'PWD': '/builds/slave/test', 11:57:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:41 INFO - 'SHELL': '/bin/bash', 11:57:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:57:41 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:57:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:41 INFO - 'USER': 'cltbld', 11:57:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:41 INFO - 'XPC_FLAGS': '0x0', 11:57:41 INFO - 'XPC_SERVICE_NAME': '0', 11:57:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:57:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:57:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:57:44 INFO - Installing collected packages: mozsystemmonitor 11:57:44 INFO - Running setup.py install for mozsystemmonitor 11:57:44 INFO - Successfully installed mozsystemmonitor 11:57:44 INFO - Cleaning up... 11:57:44 INFO - Return code: 0 11:57:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:44 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 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:57:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:44 INFO - 'HOME': '/Users/cltbld', 11:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:44 INFO - 'LOGNAME': 'cltbld', 11:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:44 INFO - 'MOZ_NO_REMOTE': '1', 11:57:44 INFO - 'NO_EM_RESTART': '1', 11:57:44 INFO - 'PAGER': '/bin/cat', 11:57:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:44 INFO - 'PWD': '/builds/slave/test', 11:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:44 INFO - 'SHELL': '/bin/bash', 11:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:57:44 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:44 INFO - 'USER': 'cltbld', 11:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:44 INFO - 'XPC_FLAGS': '0x0', 11:57:44 INFO - 'XPC_SERVICE_NAME': '0', 11:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:45 INFO - Downloading/unpacking jsonschema==2.5.1 11:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:57:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:48 INFO - Downloading functools32-3.2.3-2.tar.gz 11:57:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:57:48 INFO - warning: no files found matching '*.txt' 11:57:48 INFO - no previously-included directories found matching 'build' 11:57:48 INFO - no previously-included directories found matching 'dist' 11:57:48 INFO - no previously-included directories found matching '.git*' 11:57:48 INFO - Installing collected packages: jsonschema, functools32 11:57:48 INFO - Running setup.py install for functools32 11:57:48 INFO - warning: no files found matching '*.txt' 11:57:48 INFO - no previously-included directories found matching 'build' 11:57:48 INFO - no previously-included directories found matching 'dist' 11:57:48 INFO - no previously-included directories found matching '.git*' 11:57:48 INFO - Successfully installed jsonschema functools32 11:57:48 INFO - Cleaning up... 11:57:48 INFO - Return code: 0 11:57:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:57:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:48 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 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:57:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:57:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:57:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:57:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:48 INFO - 'HOME': '/Users/cltbld', 11:57:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:48 INFO - 'LOGNAME': 'cltbld', 11:57:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:48 INFO - 'MOZ_NO_REMOTE': '1', 11:57:48 INFO - 'NO_EM_RESTART': '1', 11:57:48 INFO - 'PAGER': '/bin/cat', 11:57:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:48 INFO - 'PWD': '/builds/slave/test', 11:57:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:48 INFO - 'SHELL': '/bin/bash', 11:57:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:57:48 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:57:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:48 INFO - 'USER': 'cltbld', 11:57:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:48 INFO - 'XPC_FLAGS': '0x0', 11:57:48 INFO - 'XPC_SERVICE_NAME': '0', 11:57:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:57:48 INFO - Cleaning up... 11:57:48 INFO - Return code: 0 11:57:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:57:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:48 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 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:57:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:57:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:57:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:57:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:48 INFO - 'HOME': '/Users/cltbld', 11:57:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:48 INFO - 'LOGNAME': 'cltbld', 11:57:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:48 INFO - 'MOZ_NO_REMOTE': '1', 11:57:48 INFO - 'NO_EM_RESTART': '1', 11:57:48 INFO - 'PAGER': '/bin/cat', 11:57:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:48 INFO - 'PWD': '/builds/slave/test', 11:57:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:48 INFO - 'SHELL': '/bin/bash', 11:57:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:57:48 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:57:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:48 INFO - 'USER': 'cltbld', 11:57:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:48 INFO - 'XPC_FLAGS': '0x0', 11:57:48 INFO - 'XPC_SERVICE_NAME': '0', 11:57:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:49 INFO - Downloading/unpacking blobuploader==1.2.4 11:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:51 INFO - Downloading blobuploader-1.2.4.tar.gz 11:57:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:57:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:57:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:52 INFO - Downloading docopt-0.6.1.tar.gz 11:57:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:57:52 INFO - Installing collected packages: blobuploader, requests, docopt 11:57:52 INFO - Running setup.py install for blobuploader 11:57:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:57:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:57:52 INFO - Running setup.py install for requests 11:57:53 INFO - Running setup.py install for docopt 11:57:53 INFO - Successfully installed blobuploader requests docopt 11:57:53 INFO - Cleaning up... 11:57:53 INFO - Return code: 0 11:57:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57: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')]} 11:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:53 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 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:57:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:57:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:57:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:57:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:53 INFO - 'HOME': '/Users/cltbld', 11:57:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:53 INFO - 'LOGNAME': 'cltbld', 11:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:53 INFO - 'MOZ_NO_REMOTE': '1', 11:57:53 INFO - 'NO_EM_RESTART': '1', 11:57:53 INFO - 'PAGER': '/bin/cat', 11:57:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:53 INFO - 'PWD': '/builds/slave/test', 11:57:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:53 INFO - 'SHELL': '/bin/bash', 11:57:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:57:53 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:57:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:53 INFO - 'USER': 'cltbld', 11:57:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:53 INFO - 'XPC_FLAGS': '0x0', 11:57:53 INFO - 'XPC_SERVICE_NAME': '0', 11:57:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:53 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-NnpPSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-jRGHrF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-g5PTPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-KT5Wlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-TiqyDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-8DIT9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-1nE2vW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-CdmI8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-FgBdU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Djj54S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-HZgII4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Y2vRtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-_3bvkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-2LC4Kv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-GAoqcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-yPmTDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Rz084x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:57:55 INFO - Running setup.py install for manifestparser 11:57:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:57:55 INFO - Running setup.py install for mozcrash 11:57:55 INFO - Running setup.py install for mozdebug 11:57:56 INFO - Running setup.py install for mozdevice 11:57:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:57:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:57:56 INFO - Running setup.py install for mozfile 11:57:56 INFO - Running setup.py install for mozhttpd 11:57:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:57:56 INFO - Running setup.py install for mozinfo 11:57:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:57:56 INFO - Running setup.py install for mozInstall 11:57:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:57:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:57:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:57:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:57:56 INFO - Running setup.py install for mozleak 11:57:56 INFO - Running setup.py install for mozlog 11:57:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:57:57 INFO - Running setup.py install for moznetwork 11:57:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:57:57 INFO - Running setup.py install for mozprocess 11:57:57 INFO - Running setup.py install for mozprofile 11:57:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:57:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:57:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:57:57 INFO - Running setup.py install for mozrunner 11:57:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:57:57 INFO - Running setup.py install for mozscreenshot 11:57:57 INFO - Running setup.py install for moztest 11:57:58 INFO - Running setup.py install for mozversion 11:57:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:57:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:57:58 INFO - Cleaning up... 11:57:58 INFO - Return code: 0 11:57:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:57:58 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 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:57:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:57:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:57:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:58 INFO - 'HOME': '/Users/cltbld', 11:57:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:58 INFO - 'LOGNAME': 'cltbld', 11:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:58 INFO - 'MOZ_NO_REMOTE': '1', 11:57:58 INFO - 'NO_EM_RESTART': '1', 11:57:58 INFO - 'PAGER': '/bin/cat', 11:57:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:58 INFO - 'PWD': '/builds/slave/test', 11:57:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:58 INFO - 'SHELL': '/bin/bash', 11:57:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:57:58 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:57:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:58 INFO - 'USER': 'cltbld', 11:57:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:58 INFO - 'XPC_FLAGS': '0x0', 11:57:58 INFO - 'XPC_SERVICE_NAME': '0', 11:57:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:58 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-cYLUK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:58 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-F8CkVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:58 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-K2zrE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:58 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-8ao_Uf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:57:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:58 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-CXZBgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-2MwJck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-8Qyrjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-obVqZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-uIYta7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-cpasZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-eAX7v4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-WiykA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-eEyXIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-jQmm7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-RdWy09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:58:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-nFrbdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:58:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-9MgSHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:58:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:58:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:58:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:58:02 INFO - Downloading blessings-1.6.tar.gz 11:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:58:02 INFO - Installing collected packages: blessings 11:58:02 INFO - Running setup.py install for blessings 11:58:03 INFO - Successfully installed blessings 11:58:03 INFO - Cleaning up... 11:58:03 INFO - Return code: 0 11:58:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:58:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:03 INFO - Reading from file tmpfile_stdout 11:58:03 INFO - Current package versions: 11:58:03 INFO - blessings == 1.6 11:58:03 INFO - blobuploader == 1.2.4 11:58:03 INFO - docopt == 0.6.1 11:58:03 INFO - functools32 == 3.2.3-2 11:58:03 INFO - jsonschema == 2.5.1 11:58:03 INFO - manifestparser == 1.1 11:58:03 INFO - mozInstall == 1.12 11:58:03 INFO - mozcrash == 0.17 11:58:03 INFO - mozdebug == 0.1 11:58:03 INFO - mozdevice == 0.48 11:58:03 INFO - mozfile == 1.2 11:58:03 INFO - mozhttpd == 0.7 11:58:03 INFO - mozinfo == 0.9 11:58:03 INFO - mozleak == 0.1 11:58:03 INFO - mozlog == 3.3 11:58:03 INFO - moznetwork == 0.27 11:58:03 INFO - mozprocess == 0.23 11:58:03 INFO - mozprofile == 0.28 11:58:03 INFO - mozrunner == 6.12 11:58:03 INFO - mozscreenshot == 0.1 11:58:03 INFO - mozsystemmonitor == 0.3 11:58:03 INFO - moztest == 0.7 11:58:03 INFO - mozversion == 1.4 11:58:03 INFO - psutil == 3.1.1 11:58:03 INFO - requests == 1.2.3 11:58:03 INFO - wsgiref == 0.1.2 11:58:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:03 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 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:58:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:58:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:58:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:03 INFO - 'HOME': '/Users/cltbld', 11:58:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:03 INFO - 'LOGNAME': 'cltbld', 11:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:03 INFO - 'MOZ_NO_REMOTE': '1', 11:58:03 INFO - 'NO_EM_RESTART': '1', 11:58:03 INFO - 'PAGER': '/bin/cat', 11:58:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:03 INFO - 'PWD': '/builds/slave/test', 11:58:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:03 INFO - 'SHELL': '/bin/bash', 11:58:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:58:03 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:58:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:03 INFO - 'USER': 'cltbld', 11:58:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:03 INFO - 'XPC_FLAGS': '0x0', 11:58:03 INFO - 'XPC_SERVICE_NAME': '0', 11:58:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:58:03 INFO - Cleaning up... 11:58:03 INFO - Return code: 0 11:58:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:58:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:58:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:58:03 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 0x10d0ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d194618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fc34b0460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, '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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:58:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:58:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:58:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:03 INFO - 'HOME': '/Users/cltbld', 11:58:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:03 INFO - 'LOGNAME': 'cltbld', 11:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:03 INFO - 'MOZ_NO_REMOTE': '1', 11:58:03 INFO - 'NO_EM_RESTART': '1', 11:58:03 INFO - 'PAGER': '/bin/cat', 11:58:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:03 INFO - 'PWD': '/builds/slave/test', 11:58:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:03 INFO - 'SHELL': '/bin/bash', 11:58:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:58:03 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:58:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:03 INFO - 'USER': 'cltbld', 11:58:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:03 INFO - 'XPC_FLAGS': '0x0', 11:58:03 INFO - 'XPC_SERVICE_NAME': '0', 11:58:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:58:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:58:03 INFO - Cleaning up... 11:58:03 INFO - Return code: 0 11:58:03 INFO - Running post-action listener: _resource_record_post_action 11:58:03 INFO - Running post-action listener: _start_resource_monitoring 11:58:03 INFO - Starting resource monitoring. 11:58:03 INFO - [mozharness: 2016-09-06 18:58:03.970626Z] Finished create-virtualenv step (success) 11:58:03 INFO - [mozharness: 2016-09-06 18:58:03.971651Z] Running install step. 11:58:03 INFO - Running pre-action listener: _resource_record_pre_action 11:58:03 INFO - Running main action method: install 11:58:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:58:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:58:04 INFO - Reading from file tmpfile_stdout 11:58:04 INFO - Detecting whether we're running mozinstall >=1.0... 11:58:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:58:04 INFO - Reading from file tmpfile_stdout 11:58:04 INFO - Output received: 11:58:04 INFO - Usage: mozinstall [options] installer 11:58:04 INFO - Options: 11:58:04 INFO - -h, --help show this help message and exit 11:58:04 INFO - -d DEST, --destination=DEST 11:58:04 INFO - Directory to install application into. [default: 11:58:04 INFO - "/builds/slave/test"] 11:58:04 INFO - --app=APP Application being installed. [default: firefox] 11:58:04 INFO - mkdir: /builds/slave/test/build/application 11:58:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:58:40 INFO - Reading from file tmpfile_stdout 11:58:40 INFO - Output received: 11:58:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:58:40 INFO - Running post-action listener: _resource_record_post_action 11:58:40 INFO - [mozharness: 2016-09-06 18:58:40.050837Z] Finished install step (success) 11:58:40 INFO - [mozharness: 2016-09-06 18:58:40.050985Z] Running run-tests step. 11:58:40 INFO - Running pre-action listener: _resource_record_pre_action 11:58:40 INFO - Running main action method: run_tests 11:58:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:58:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:58:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:58:40 INFO - 2016-09-06 11:58:40.065 screenresolution[1648:7862] starting screenresolution argv=screenresolution get 11:58:40 INFO - 2016-09-06 11:58:40.068 screenresolution[1648:7862] Display 0: 1600x1200x32@60 11:58:40 INFO - 2016-09-06 11:58:40.074 screenresolution[1649:7864] starting screenresolution argv=screenresolution list 11:58:40 INFO - Available Modes on Display 0 11:58:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:58:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:58:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:58:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:58:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:58:40 INFO - Intel Iris: 11:58:40 INFO - Chipset Model: Intel Iris 11:58:40 INFO - Type: GPU 11:58:40 INFO - Bus: Built-In 11:58:40 INFO - VRAM (Dynamic, Max): 1536 MB 11:58:40 INFO - Vendor: Intel (0x8086) 11:58:40 INFO - Device ID: 0x0a2e 11:58:40 INFO - Revision ID: 0x0009 11:58:40 INFO - Displays: 11:58:40 INFO - Display: 11:58:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:58:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:58:40 INFO - Display Serial Number: 1600x1200 60 11:58:40 INFO - Main Display: Yes 11:58:40 INFO - Mirror: Off 11:58:40 INFO - Online: Yes 11:58:40 INFO - Rotation: Supported 11:58:40 INFO - Return code: 0 11:58:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:58:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:58:40 INFO - Python 2.7.3 11:58:40 INFO - Return code: 0 11:58:40 INFO - grabbing minidump binary from tooltool 11:58:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:58:40 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 0x10d063d30>, '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 0x10cdc8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:58:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:58:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:58:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:58:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:58:40 INFO - Return code: 0 11:58:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:58:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:58:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:58:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:58:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:58:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:58:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0132 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:58:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co8jRS0z3q/Render', 11:58:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:58:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:58:40 INFO - 'HOME': '/Users/cltbld', 11:58:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:58:40 INFO - 'LOGNAME': 'cltbld', 11:58:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:58:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:58:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:58:40 INFO - 'MOZ_NO_REMOTE': '1', 11:58:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:58:40 INFO - 'NO_EM_RESTART': '1', 11:58:40 INFO - 'PAGER': '/bin/cat', 11:58:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:58:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:58:40 INFO - 'PWD': '/builds/slave/test', 11:58:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:58:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:58:40 INFO - 'SHELL': '/bin/bash', 11:58:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.s7Ic3gD3VN/Listeners', 11:58:40 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 11:58:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:58:40 INFO - 'USER': 'cltbld', 11:58:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:58:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:58:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:58:40 INFO - 'XPC_FLAGS': '0x0', 11:58:40 INFO - 'XPC_SERVICE_NAME': '0', 11:58:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:58:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:58:40 INFO - mozversion application_buildid: 20160906094754 11:58:40 INFO - mozversion application_changeset: 236f750b6a3002e91e551fd6c2ddec63995a6e0e 11:58:40 INFO - mozversion application_display_name: Nightly 11:58:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:58:40 INFO - mozversion application_name: Firefox 11:58:40 INFO - mozversion application_remotingname: firefox 11:58:40 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 11:58:40 INFO - mozversion application_vendor: Mozilla 11:58:40 INFO - mozversion application_version: 51.0a1 11:58:40 INFO - mozversion platform_buildid: 20160906094754 11:58:40 INFO - mozversion platform_changeset: 236f750b6a3002e91e551fd6c2ddec63995a6e0e 11:58:40 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 11:58:40 INFO - mozversion platform_version: 51.0a1 11:58:40 INFO - using testdate: 1473188320 11:58:40 INFO - actual date: 1473188320 11:58:40 INFO - starting webserver on 'localhost:49277' 11:58:40 INFO - SUITE-START | Running 2 tests 11:58:40 INFO - TEST-START | dromaeo_css 11:58:40 INFO - operating with platform_type : mac_ 11:58:40 INFO - Initialising browser for dromaeo_css test... 11:58:40 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpmioA5r/profile http://localhost:49277/getInfo.html) 11:58:43 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 11:58:43 INFO - PROCESS | 1659 | colorDepth:24 11:58:43 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 11:58:43 INFO - PROCESS | 1659 | __metrics 11:58:43 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:58:43 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:58:43 INFO - TEST-INFO | 1659: exit 0 11:58:43 INFO - Browser initialized. 11:58:43 INFO - Running cycle 1/1 for dromaeo_css test... 11:58:43 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpmioA5r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:56 INFO - PROCESS | 1662 | RSS: Main: 244772864 11:58:56 INFO - PROCESS | 1662 | 11:59:46 INFO - PROCESS | 1662 | 1473188386928 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] 12:01:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 12:02:00 INFO - PROCESS | 1662 | RSS: Main: 336076800 12:02:00 INFO - PROCESS | 1662 | 12:05:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 12:05:03 INFO - PROCESS | 1662 | RSS: Main: 370012160 12:05:03 INFO - PROCESS | 1662 | 12:08:06 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 12:08:06 INFO - PROCESS | 1662 | RSS: Main: 337928192 12:08:06 INFO - PROCESS | 1662 | 12:11:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 12:11:09 INFO - PROCESS | 1662 | RSS: Main: 337154048 12:11:09 INFO - PROCESS | 1662 | 12:14:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 12:14:13 INFO - PROCESS | 1662 | RSS: Main: 330719232 12:14:13 INFO - PROCESS | 1662 | 12:17:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 12:17:17 INFO - PROCESS | 1662 | RSS: Main: 607232000 12:17:17 INFO - PROCESS | 1662 | 12:17:17 INFO - PROCESS | 1662 | __start_tp_report 12:17:17 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 12:17:17 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 12:17:17 INFO - PROCESS | 1662 | |i|pagename|runs| 12:17:17 INFO - PROCESS | 1662 | |0;dojo.html;2663.9467210655785;3064.187990182602;3079.7382222511087;3315.2043509557707;3372.207531230159;5612.186232996223;5689.288838895138;5699.116636921273;5754.568407369448;5787.56593255506;4693.342931989524;5724.599278050528;5813.941860581393;5822.7088645567765;5858.297004359472;5144.4598317176715;5148.948510514903;5212.192110222922;5237.79048838046;5404.540614047791;5282.943411317732;5331.6534425262425;5341.572674186055;5345.732713364336;5362.490563396471;5150.150225212836;5154.74226288686;5159.5614372778355;5203.089459344599;5238.345206849144;5193.221016847466;5212.791488340462;5220.373555173365;5265.973670131663;5269.841904742864;5173.146430838907;5192.402873669538;5211.921821172686;5229.869253268661;5233.058049551079;5596.748146333425;5642.858928526779;5698.34469036062;5780.161876527922;5804.680742559158;6197.504199664016;6239.157713708637;6269.8746025079245;6279.560430769848;6285.402886725754;6264.404881536247;6268;6275.592086514362;6275.843103922392;6277.748890044393;5701.486866182064;5736.77052917887;5747.080467125324;5795.82612521625;5817.680027598481;6202.782902598477;6238.688065596702;6255.655938923312;6259.561830671809;6264;6124.050772130327;6141.293751218667;6150.3849615039035;6152.446279834837;6162.106494558264;6114.449699527064;6135.110179431455;6197.659128747962;6272.749090036439;6276.717547710364;6100.944524685232;6115.666295175888;6153.800008998202;6229.0967809667345;6247.9062814057825;6251.562390632612;6253.3121356651645;6265.373462653698;6278.246610406689;6305.085762564402;4815.374001379798;4982.701037937735;4993.50064993505;4995.375578052744;5021.995600879839;4399.38408622793;4402.625776809007;4466.151431228056;4492.483364413069;4548.613367863769;5673.262475878109;5674.290713660793;5683.2895888014;5691.4877661010705;5695.686737229492;6236.064590311399;6308.369163083655;6323.146375239376;6325.5255855811;6329.556931014877;5087.262346959744;5534.363548192009;5549.417311182267;5589.552835773128;5643.068893632587;5387.461253874581;5462.426445223274;5478.506934375925;5525.502704756511;5539.723013849372;6249.7500099997305;6272.717727702265;6280.685965701605;6283.2460104787715;6287.748490060347;6259.405356491126;6265.373462653698;6276.623402595859;6306.337834527492;6315.684215789101;6211.0372892201785;6216.191895053614;6235.220597425322;6258.812235632939;6319.494440444661;6210.627362358092;6211.2857021441305;6219.346968568235;6232.127502149793;6233.252009758858;6156.6921653918025;6274.623522588659;6300.7479700811455;6314.179156709598;6319.304876463677;5960.910586341121;6032.698365081641;6066.878662426639;6108.297545782292;6146.692665366624;6100.163796098639;6104.822969073627;6106.399996025681;6107.735039974475;6127.9837278401355;5932.466078052996;5954.285485741739;5972.223610930551;5977.252843394575;6004.669743163993;5903.498202652822;6024.7288872001745;6094.939050609437;6107.54193435503;6121.788327166137;5872;5933.762649494144;5949.1076338549565;5953.851153721192;5996.130561068708 12:17:17 INFO - PROCESS | 1662 | |1;ext.html;465.03505226654687;474.8884012257119;494.82186412891355;536.8738346488577;554.2849723856226;29675.703242967564;30285.24286892826;38909.416358754606;40005.7999710001;40118.79940600293;40489.797551012205;40803.79598102013;40813.183736325256;40819;40972.59027409725;9596.760080997996;9750.36622619531;9753.073458021476;9759.975202603731;9797.363171393872;8356.623951922178;9606.90393096067;9614.471204083773;9615.903840961571;9649.324547281693;9641.228701703847;9674.75813104671;9679.128878400943;9708.271879609023;9710.12608865202;9583.329166958316;9637.277204209677;9638.951805240999;9647.131758141732;9662.08210220028;8353.95823020881;8431.451955622926;9624.855627165598;9626.037396260388;9630.374025688308;38502.8449146526;38819;38823.61176388228;38846.80576597099;38882.80558597189;3533.222691007974;3584.211473475857;3603.819809009539;3620.203555217874;3861.3242682530445;29646;29745.851270743507;30678.9262375818;30763.923262685923;31088.22279443032;3223.097532690827;3301.6533264007176;3312.602487701467;3344.4481660525985;3348.5312056312137;440664;444331;445606.77196614136;445779;445844.77077614405;32499.837500812344;54671.45328546664;54697.1795423069;55144;55144.79420823202;32956.83521582377;34440.655593444244;34453.655463445546;34462.31075378478;34501.48247776285;12390;14332.426702931765;14452.843772498174;14496.637584060483;14513.346899389553;12380;12462.43919023646;12475.126741128033;12476.18904771187;12491.562795302121;6614.437772789271;6647.302033286532;6660.433863121689;7709.267619576131;7721.189275126143;2881.250874772574;3191.457452233126;3254.560634314337;3256.362346499343;3289.292802047571;805.367786287773;940.8118376324762;987.3138168972495;1031.8194315994672;1045.3153184664056;28861.56707649387;29357.559636605467;29390.559141612892;29966.70033299639;30052.398952020838;918.0177210384824;1030.1501261459177;1032.2052019944601;1048.5805677728817;1212.5755985405037;12850.67873303175;14593.562193134223;15158.090514569161;15209.847901521065;15594.064356138668;587970;588097;589572;593369;593467;54832;55237;55288.17067743987;55426.72286638541;55470;1194.2416565480808;1252.0922331309728;1370.4449697872178;1392.5822253323963;1412.9081609695336;30010.399792004475;30144.547831782103;30318.848405758712;30892.38215235638;32306.515402268513;53865.461345387615;54623.726881365335;54659.72670136783;54686;54822.45177548333;606802;607666;608366;612419;612454;34540.48189277213;40494.392584110676;41239.96900077402;41366.37950430806;41519.792401037805;45399;45658.54341456676;46207.53792462032;46407.071858563315;47044.7647761759;37584.060398490255;37596.62403375931;37805.961629147074;38469;39099.60900391074;37546;38109;38750.80624596859;39018.80490597529;39181;36570.63429365672;36704;36791.26417471689;37360.813195934934;37645;28076.85961570179;31237.062888113393;32297.031089067365;33764.66235337615;33830;24418.755812441646;24526;24632.78304952491;25287.620685689362;25557.23328300154 12:17:17 INFO - PROCESS | 1662 | |2;jquery.html;333.78470886278365;350.1910586545081;351.4096318185449;356.4243746349645;381.7079933850605;15359.616009599766;15532.45636402726;16069.75895361569;16342.754858677099;17260;17338.739918901196;19940.9002954985;20110;20356;20530.589388212225;5625.718714064291;5934.228550288456;5946.375630558794;5946.881062378749;5951.642901425928;5353;5802.506786923117;5862;5872.442117998805;5944.2866855977345;5879.32387775407;5882.323532793719;5883.646981181121;5887.8528036799;5891.734871930752;5618.381977982419;5746.683932383718;5799.36207017232;5803.767849286023;5812.070068788974;5025.773840177201;5031.77357018935;5036.345275114249;5041.2438134280155;5050.823221187277;13409.195448273134;13626.386812593359;22786.088556457722;24449.87775061131;25552.48895022108;2018.9495262618552;2086.2802333195177;2411.9035238590436;2418.322869596516;2637.406583518712;9857;9953.30326877119;10308.381497110197;10320.058783246814;10327.58689652413;1805.873588848781;1807.8591793727533;1857.2942281932915;1858.7397764313007;1932.5168707823045;107034;119574.80425195723;121798;121855.78144218533;122879;9547.467437048337;10684.572617095384;11327;11327.433628318717;11367.612801311652;14170.024294898187;14171.78742318866;14187.361568729431;14188.85811141896;14198.929005355114;8231.382646301552;8504.659813607386;8525.914740852637;8660.13398660129;8660.8700869487;8465.703700370457;8466.619002144918;8473.57632118404;8666.913330866737;8680.175383338572;4413.602775750133;4462.330650402466;4475.149721552894;4554.931676024862;4555.681102322872;1849.1216672080654;1900.3063881683086;1907.7519922409997;1908.799576044523;1913.5120544261124;714.5212707485997;777.9769602972186;841.5329492131925;868.2359523619175;869.699953516036;19645.607087858447;20729.0671919764;21184.65714528518;21450;22514.887425563094;845.159066728609;894.9015608283083;948.6015873333216;961;1024.2164743970868;9695.127438530566;10500.159987201005;10736.838947415796;11250.381229032319;11253.549858005588;563652;565709;588832;597882;604701;92582;128065;133403.99894001597;135332;139001;936.3118108190474;987.3828856964398;1000.4047591682792;1007.8588997540351;1016.5171543516918;18689.065546722337;18818;19515.414537563895;19676.704849426984;19687.917164555514;54158.916821662555;54929.45070549243;55158;55869.72065139811;57197.42802571921;75509.93161353428;82355.17644823475;83725;85743.14256857352;85882;6843;7530.736424225126;7918.841623167619;7998.48009879356;8225.424220304521;12476;12636.620901372973;13065.804012939623;13110.213387196798;13125.934370328087;11028.338299702044;11195.384253866116;11203.775924481628;11227.49476273586;11328.037116844831;10966.561337546409;11188.042092633594;11290.49192786343;11502.252353596989;11517.654470365902;11313.377764223047;11346.602868899548;11411.315321080761;11438.542458301576;11460.94269528647;7746.760518317043;7940.801479963047;7967.721129760431;8203.466774659628;8241.95879020601;8554.957225213833;10814.837777433502;11131.053860421955;11155.219134660496;11174.217804753589 12:17:17 INFO - PROCESS | 1662 | |3;mootools.html;1485.3835658201847;1498.1760031982408;1711.2203308559099;1884.3404808317082;1920.932767353143;7854;8192.221738934792;8394.706185283516;8919.15268049536;8928.642854285828;8331.667133202698;8648.437851539646;8653.091425400336;8750.168733970278;8755.430896991707;2262.298687406901;2295.82781291403;2299.241250387368;2300.5858945389823;2303.884805759714;2018.354126679456;2098.590774798915;2257.5033492631596;2288.0733303012307;2311.05246848792;2071.989640051805;2089.7387826521685;2204.9779502204933;2285.714285714286;2290.7938285554296;2275.1581914691506;2282.3153054083873;2282.9200977965857;2285.325828880484;2302.4474126209757;2239.3617818921693;2269.7730226977337;2280.817534597228;2283.942901427461;2289.6565515172692;8660.913390866137;8843.690470833555;8845.601947912359;8856.690015849414;8858.29133669305;4955.355803745526;5063.974680126576;5085.415177254626;5091.821786237464;5099.286099946011;3315.53582498448;3319.9834000830083;3370.5112758650102;3398.796072235674;3455.671711187433;1009.0918173643706;1012.9797404051951;1026.296986564206;1039.7868436970477;1041.468850886046;11409.543618255177;11413.233632901205;11429.42852857362;11429.542818287258;11432.771344573062;4206.768627725504;4265.786710664454;4355.433793606874;4434.13534360803;4446.555344465528;8809.515476648847;9972.351797133164;9977.900220997843;9980.351277166961;9983.35108217978;5095.745212739348;5104.3619547556555;5108.846734597893;5112.182050871843;5122.410922743858;4548.795304211319;4600.378948841931;4689.366935463669;4695.272232803921;4698.154332220165;3667.1198912260866;3675.9264814703556;3691.9261614767556;3776.6223377662013;3824.349860523718;1119;1131.2364154195884;1144.7710457908452;1158.449736375215;1158.6987383280407;601.0983524712931;744.0624812735991;870.9085546017703;876.4609764994483;940.5062342270226;5636.464535869086;5768;5917.467427931507;6013.128096425994;6028.487578555784;873.9431936924207;887.8401887660154;891.8138875295841;950.7385468995943;959.3380567408466;3881.3983832506005;3923.6861051115843;3933.9016524587114;4039.2325458162854;4056.9594304057173;10371.896281037243;10702.892971070345;10764.623238186627;10777.676669699922;10797.406142662072;10715.571377144983;10739.624113156002;10739.731506712395;10757.78484430307;10760.515776790064;6551.934480655133;7077.256888026683;8163.755087347389;8192.54940978252;8262.504249745034;5401.945980540144;5985.132155837467;6089.147519347383;6098.237720284965;6186.659733714689;9608.327417080738;10007.049330313608;10031;10149.949250253701;10188.28681992283;9830.050984704703;10154;10339.121174700234;10381.27331086847;10386.42874641902;7344.70621175147;7568.356689681439;7649.732259370895;7658.119316278699;7678.347340476123;8259.628316725883;8336.833263334578;8343;8344.7913802153;8348.081711011662;7110.146782386147;7446.180920098893;7459.738909138154;7538.434617403607;7645.541267523967;7275.599842008741;7381.704731810667;7388.224236455095;7469.066366704162;7469.14104877946;6929.688164032731;7037.1555413350725;7046.647667616701;7054.823629409306;7057.470689698191;4200.6219440250525;4925.876853078702;5030.798768049383;5059.822906198266;5129.999650068201;4916.901661966812;5044.4451110377095;5105.642605017613;5129.204973229155;5230.712310822942 12:17:17 INFO - PROCESS | 1662 | |4;prototype.html;238.41944864255538;276.3850432787049;285.75996163222896;288.96676882158545;291.5946833900877;4694.342792009113;4728.952710472894;4748.145333839908;5394.5954053446;5397.4332695067;4695.647826413024;5162.741862906859;5241.475852414756;5346.893062138755;5409.594280428964;3439.8452069656923;4099.180163967204;4106.958930410702;4126.607972242639;4138.6482149017365;3633.3278343506404;3691.575468821093;3790.469334293201;3926.155876486555;4132;3446.3968805458944;3808.752431091984;3936.822842972059;4103.651189648868;4112.773797441139;3421.5209870618132;4066.4510291110764;4072.7352722073265;4082.2447847148374;4083.325836407059;3191.9042428726943;3400.401811148781;3571.0715214044535;3573.535440392758;3596.8741094061584;4744.905101897977;5023.7236951967625;5112.539871411591;5248.291480650101;5277.551408130314;3789.905252368685;4667.579917807379;4670.579647831712;4671.346011558385;4688.554587314234;5016.147254966664;5537.556995440355;5575.539568345307;5587.413321601214;5625.212470254168;3734.0104872208613;3754.7758771382196;3826.215625796705;3870.148567315186;3873.283442563135;4640.86077417678;5232.319798426256;5921.83852893695;5996.700164991733;6002.96998515009;3937.9409308859817;3990.620891015349;4003.979980100081;4006.358982562776;4006.579309172553;3862.1889403225637;4022;4026.617471340218;4046.6560342371226;4049.3318602430268;3869.7616762635685;4064.5570822358227;4610.792514336863;4662.510436404129;4688.718676879398;5200.583953283728;5600.963821692918;5642.2479051431155;5650.29371328584;5673.404292549306;1171.6467480060544;1203.1577895473204;1234.5370486067725;1268.2517314784322;1350.216874212954;852.9402941794139;869.8303830752942;880.3925291548811;937.8874535055701;938.2869019545195;4614.531225794184;4885.802978270275;5054.292399064134;5247.2653828464045;5258.789648414021;4658.394408726844;5124.948750512522;5753.395893431213;5813.680247586339;5827.650340979555;4391.407160033418;4847.3698419205275;4863.829765958245;4931.4821943696115;5189.610779191576;2248.1681777742015;2385.7375688674233;2524.9521448598994;2532.7593878581506;2536.4546622475887;4303.612674859278;4611.469680986663;4930.753462326869;4946.678465899705;4953.777080031407;4043.838246470167;4496.392986946786;4788.545088216614;4879.219324908068;4925.335079764186;4551.362809206714;4554.76611605757;4765.69023013503;4956.3804524434445;4958.42978057528;4186.623203911662;4548.476260452785;4712.057588482386;4779.569838714532;4862.6839255448285;3778.4332350147697;4324.56754324565;4415.624671902924;4665.136949664323;4692.202325604723;4580.450345958507;4723.6929599575915;4741.5021422750115;4892.535209155125;5036.496350364934;3062.3262882165886;3232.2404235005224;3310.470324748125;3365.663433656615;3370.292238629817;4281.507626622978;4288.871333859989;4714.114354258324;4770.6843743597865;5487.725613719218;4875;5416.431274716098;5605.1872478491205;5625;5644.2380278663495;5629.634073785191;5632.324121105494;5639.251831932114;5657.575681823797;5666.886662266814;5192.818251361185;5480.095784195722;5493.285872836505;5507.614466987433;5561.416051314553;5599.580031497572;5619.8595035124445;5621.269234999424;5630.943690563042;5665.376808551138;4935.605151587936;5447.945520544744;5485.6434331768305;5524.961271300849;5556.888862222814 12:17:17 INFO - PROCESS | 1662 | |5;yui.html;648.5687018132942;717.2433083097337;764.7705688293511;792.4928046050528;814.645629151319;15106.924465377671;15926.920365398157;15933.123678197695;15937.282822273011;16023;14162.433502659907;14264;14277.428902843874;14283.357248923798;14736.484223052195;667.9666016699146;687.4740823270203;806.9112397636255;828.2214718164936;841.0286119531951;836.6778790165799;851.7317045130795;854.6666799948025;855.0081904990212;855.4738835616082;899.3614533681094;901.5447199164413;901.8602116671926;902.4314681750488;904.2314033071902;709.8473828126926;879.4415546128255;897.9012308646011;897.9281657467419;898.8382091223642;661.5369241530899;667.0494545273015;671.5500614588188;672.4317951331118;672.8586996730693;14891.180985045818;14939.551813445614;14939.850601493954;14973.700525989529;15053.774193387108;13756.449742010307;13890.513832015831;14084.92957535216;14151.575452736435;14303.28483575817;12777.425015874307;12792.48830046797;12843.422046007952;12933.48266069356;13106.2136271824;4380.364847097185;4399.36209249657;4428.2914733642465;4524.411826462571;4525.5021947586065;114606;115050;115337;116186;118193.40903295514;10847.51186196623;11044.779104417867;11105.888941110647;11170.888291117148;11249;21847;21954.121835126414;21962.364516257832;21984.89007554984;22001.22995695175;7569.848603027908;7727.1886451922865;7756.961215194001;7865.729960926194;8080.353571714249;4313.784310784511;4333.92892594801;4395.25280702275;4411;4540.704854184533;3398.4052790761516;3549.8757543485854;3784.2242340320167;3992.0219546210783;3992.0818211811447;716.9749058782918;724.644923987253;732.1287667675449;735.1582438108443;739.0577014306801;619.5322531488697;634.9968250158812;645.7258977617147;649.5420728386476;652.4539129829298;9159.95420022909;9429.952850235704;9514.672742626215;9544.570494327772;9613.663521776843;526.8129813916081;578.4302462074853;586.4194447497033;600.1627729317578;615.2217444932164;4427.690061695715;4624.237000894815;4645.419322584677;4667.113248482778;4831.4685384607665;155865.2206738959;161733.38266617182;166812.16593917194;167509;172057;141067;146123;146760.2661986683;147919.26040369945;149666;120565;121501.3924930405;123232.15151772731;125363;126571.36714316554;8350.874736879074;8909.643614255616;8942.239909607757;9156.633734650806;9174.541272936192;46374.76812615915;54860.45139548713;64295.67852160709;65949;66112.67774644577;85896;97609;98542;98572;101206;11367.374794386387;11877.406129693309;11971.880281197426;12047.759044818878;12105.81841272364;12953;13459.663508412368;13558.864411356155;13561.45754169822;13841.792373114613;11895.940520297343;11986.520539178335;12501.624951251477;12639.747205056032;12768;12071.275723456622;12309.75380492403;12551.87448125507;12617.306048167438;12682.492700291885;12183.451744671689;12215.206011609216;12442.626721198378;12477.251364918135;12591.307478088793;9925.900740992498;10466.744227318342;10532.89467105319;10724.088452481627;10761.246712730346;8341.09531043748;9476.715698529055;10043.949780251052;10201.97980201974;10475.109615682752 12:17:17 INFO - PROCESS | 1662 | __end_tp_report 12:17:17 INFO - PROCESS | 1662 | __start_cc_report 12:17:17 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1414 12:17:17 INFO - PROCESS | 1662 | __end_cc_report 12:17:17 INFO - PROCESS | 1662 | __startTimestamp1473189437206__endTimestamp 12:17:17 INFO - PROCESS | 1662 | 12:17:17 INFO - PROCESS | 1662 | ------- Summary: start ------- 12:17:17 INFO - PROCESS | 1662 | Number of tests: 6 12:17:17 INFO - PROCESS | 1662 | 12:17:17 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5739.41 Median:5960.91 stddev:671.19 (11.3%) stddev-sans-first:628.39 12:17:17 INFO - PROCESS | 1662 | Values: 2663.9 3064.2 3079.7 3315.2 3372.2 5612.2 5689.3 5699.1 5754.6 5787.6 4693.3 5724.6 5813.9 5822.7 5858.3 5144.5 5148.9 5212.2 5237.8 5404.5 5282.9 5331.7 5341.6 5345.7 5362.5 5150.2 5154.7 5159.6 5203.1 5238.3 5193.2 5212.8 5220.4 5266.0 5269.8 5173.1 5192.4 5211.9 5229.9 5233.1 5596.7 5642.9 5698.3 5780.2 5804.7 6197.5 6239.2 6269.9 6279.6 6285.4 6264.4 6268.0 6275.6 6275.8 6277.7 5701.5 5736.8 5747.1 5795.8 5817.7 6202.8 6238.7 6255.7 6259.6 6264.0 6124.1 6141.3 6150.4 6152.4 6162.1 6114.4 6135.1 6197.7 6272.7 6276.7 6100.9 6115.7 6153.8 6229.1 6247.9 6251.6 6253.3 6265.4 6278.2 6305.1 4815.4 4982.7 4993.5 4995.4 5022.0 4399.4 4402.6 4466.2 4492.5 4548.6 5673.3 5674.3 5683.3 5691.5 5695.7 6236.1 6308.4 6323.1 6325.5 6329.6 5087.3 5534.4 5549.4 5589.6 5643.1 5387.5 5462.4 5478.5 5525.5 5539.7 6249.8 6272.7 6280.7 6283.2 6287.7 6259.4 6265.4 6276.6 6306.3 6315.7 6211.0 6216.2 6235.2 6258.8 6319.5 6210.6 6211.3 6219.3 6232.1 6233.3 6156.7 6274.6 6300.7 6314.2 6319.3 5960.9 6032.7 6066.9 6108.3 6146.7 6100.2 6104.8 6106.4 6107.7 6128.0 5932.5 5954.3 5972.2 5977.3 6004.7 5903.5 6024.7 6094.9 6107.5 6121.8 5872.0 5933.8 5949.1 5953.9 5996.1 12:17:17 INFO - PROCESS | 1662 | 12:17:17 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:66971.15 Median:38864.81 stddev:147995.57 (380.8%) stddev-sans-first:148326.50 12:17:17 INFO - PROCESS | 1662 | Values: 465.0 474.9 494.8 536.9 554.3 29675.7 30285.2 38909.4 40005.8 40118.8 40489.8 40803.8 40813.2 40819.0 40972.6 9596.8 9750.4 9753.1 9760.0 9797.4 8356.6 9606.9 9614.5 9615.9 9649.3 9641.2 9674.8 9679.1 9708.3 9710.1 9583.3 9637.3 9639.0 9647.1 9662.1 8354.0 8431.5 9624.9 9626.0 9630.4 38502.8 38819.0 38823.6 38846.8 38882.8 3533.2 3584.2 3603.8 3620.2 3861.3 29646.0 29745.9 30678.9 30763.9 31088.2 3223.1 3301.7 3312.6 3344.4 3348.5 440664.0 444331.0 445606.8 445779.0 445844.8 32499.8 54671.5 54697.2 55144.0 55144.8 32956.8 34440.7 34453.7 34462.3 34501.5 12390.0 14332.4 14452.8 14496.6 14513.3 12380.0 12462.4 12475.1 12476.2 12491.6 6614.4 6647.3 6660.4 7709.3 7721.2 2881.3 3191.5 3254.6 3256.4 3289.3 805.4 940.8 987.3 1031.8 1045.3 28861.6 29357.6 29390.6 29966.7 30052.4 918.0 1030.2 1032.2 1048.6 1212.6 12850.7 14593.6 15158.1 15209.8 15594.1 587970.0 588097.0 589572.0 593369.0 593467.0 54832.0 55237.0 55288.2 55426.7 55470.0 1194.2 1252.1 1370.4 1392.6 1412.9 30010.4 30144.5 30318.8 30892.4 32306.5 53865.5 54623.7 54659.7 54686.0 54822.5 606802.0 607666.0 608366.0 612419.0 612454.0 34540.5 40494.4 41240.0 41366.4 41519.8 45399.0 45658.5 46207.5 46407.1 47044.8 37584.1 37596.6 37806.0 38469.0 39099.6 37546.0 38109.0 38750.8 39018.8 39181.0 36570.6 36704.0 36791.3 37360.8 37645.0 28076.9 31237.1 32297.0 33764.7 33830.0 24418.8 24526.0 24632.8 25287.6 25557.2 12:17:17 INFO - PROCESS | 1662 | 12:17:17 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:34462.62 Median:25001.18 stddev:97800.83 (391.2%) stddev-sans-first:98041.61 12:17:17 INFO - PROCESS | 1662 | Values: 333.8 350.2 351.4 356.4 381.7 15359.6 15532.5 16069.8 16342.8 17260.0 17338.7 19940.9 20110.0 20356.0 20530.6 5625.7 5934.2 5946.4 5946.9 5951.6 5353.0 5802.5 5862.0 5872.4 5944.3 5879.3 5882.3 5883.6 5887.9 5891.7 5618.4 5746.7 5799.4 5803.8 5812.1 5025.8 5031.8 5036.3 5041.2 5050.8 13409.2 13626.4 22786.1 24449.9 25552.5 2018.9 2086.3 2411.9 2418.3 2637.4 9857.0 9953.3 10308.4 10320.1 10327.6 1805.9 1807.9 1857.3 1858.7 1932.5 107034.0 119574.8 121798.0 121855.8 122879.0 9547.5 10684.6 11327.0 11327.4 11367.6 14170.0 14171.8 14187.4 14188.9 14198.9 8231.4 8504.7 8525.9 8660.1 8660.9 8465.7 8466.6 8473.6 8666.9 8680.2 4413.6 4462.3 4475.1 4554.9 4555.7 1849.1 1900.3 1907.8 1908.8 1913.5 714.5 778.0 841.5 868.2 869.7 19645.6 20729.1 21184.7 21450.0 22514.9 845.2 894.9 948.6 961.0 1024.2 9695.1 10500.2 10736.8 11250.4 11253.5 563652.0 565709.0 588832.0 597882.0 604701.0 92582.0 128065.0 133404.0 135332.0 139001.0 936.3 987.4 1000.4 1007.9 1016.5 18689.1 18818.0 19515.4 19676.7 19687.9 54158.9 54929.5 55158.0 55869.7 57197.4 75509.9 82355.2 83725.0 85743.1 85882.0 6843.0 7530.7 7918.8 7998.5 8225.4 12476.0 12636.6 13065.8 13110.2 13125.9 11028.3 11195.4 11203.8 11227.5 11328.0 10966.6 11188.0 11290.5 11502.3 11517.7 11313.4 11346.6 11411.3 11438.5 11460.9 7746.8 7940.8 7967.7 8203.5 8242.0 8555.0 10814.8 11131.1 11155.2 11174.2 12:17:17 INFO - PROCESS | 1662 | 12:17:17 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5637.46 Median:4940.62 stddev:3213.05 (65.0%) stddev-sans-first:3206.91 12:17:17 INFO - PROCESS | 1662 | Values: 1485.4 1498.2 1711.2 1884.3 1920.9 7854.0 8192.2 8394.7 8919.2 8928.6 8331.7 8648.4 8653.1 8750.2 8755.4 2262.3 2295.8 2299.2 2300.6 2303.9 2018.4 2098.6 2257.5 2288.1 2311.1 2072.0 2089.7 2205.0 2285.7 2290.8 2275.2 2282.3 2282.9 2285.3 2302.4 2239.4 2269.8 2280.8 2283.9 2289.7 8660.9 8843.7 8845.6 8856.7 8858.3 4955.4 5064.0 5085.4 5091.8 5099.3 3315.5 3320.0 3370.5 3398.8 3455.7 1009.1 1013.0 1026.3 1039.8 1041.5 11409.5 11413.2 11429.4 11429.5 11432.8 4206.8 4265.8 4355.4 4434.1 4446.6 8809.5 9972.4 9977.9 9980.4 9983.4 5095.7 5104.4 5108.8 5112.2 5122.4 4548.8 4600.4 4689.4 4695.3 4698.2 3667.1 3675.9 3691.9 3776.6 3824.3 1119.0 1131.2 1144.8 1158.4 1158.7 601.1 744.1 870.9 876.5 940.5 5636.5 5768.0 5917.5 6013.1 6028.5 873.9 887.8 891.8 950.7 959.3 3881.4 3923.7 3933.9 4039.2 4057.0 10371.9 10702.9 10764.6 10777.7 10797.4 10715.6 10739.6 10739.7 10757.8 10760.5 6551.9 7077.3 8163.8 8192.5 8262.5 5401.9 5985.1 6089.1 6098.2 6186.7 9608.3 10007.0 10031.0 10149.9 10188.3 9830.1 10154.0 10339.1 10381.3 10386.4 7344.7 7568.4 7649.7 7658.1 7678.3 8259.6 8336.8 8343.0 8344.8 8348.1 7110.1 7446.2 7459.7 7538.4 7645.5 7275.6 7381.7 7388.2 7469.1 7469.1 6929.7 7037.2 7046.6 7054.8 7057.5 4200.6 4925.9 5030.8 5059.8 5130.0 4916.9 5044.4 5105.6 5129.2 5230.7 12:17:17 INFO - PROCESS | 1662 | 12:17:17 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4311.46 Median:4695.00 stddev:1320.96 (28.1%) stddev-sans-first:1288.80 12:17:17 INFO - PROCESS | 1662 | Values: 238.4 276.4 285.8 289.0 291.6 4694.3 4729.0 4748.1 5394.6 5397.4 4695.6 5162.7 5241.5 5346.9 5409.6 3439.8 4099.2 4107.0 4126.6 4138.6 3633.3 3691.6 3790.5 3926.2 4132.0 3446.4 3808.8 3936.8 4103.7 4112.8 3421.5 4066.5 4072.7 4082.2 4083.3 3191.9 3400.4 3571.1 3573.5 3596.9 4744.9 5023.7 5112.5 5248.3 5277.6 3789.9 4667.6 4670.6 4671.3 4688.6 5016.1 5537.6 5575.5 5587.4 5625.2 3734.0 3754.8 3826.2 3870.1 3873.3 4640.9 5232.3 5921.8 5996.7 6003.0 3937.9 3990.6 4004.0 4006.4 4006.6 3862.2 4022.0 4026.6 4046.7 4049.3 3869.8 4064.6 4610.8 4662.5 4688.7 5200.6 5601.0 5642.2 5650.3 5673.4 1171.6 1203.2 1234.5 1268.3 1350.2 852.9 869.8 880.4 937.9 938.3 4614.5 4885.8 5054.3 5247.3 5258.8 4658.4 5124.9 5753.4 5813.7 5827.7 4391.4 4847.4 4863.8 4931.5 5189.6 2248.2 2385.7 2525.0 2532.8 2536.5 4303.6 4611.5 4930.8 4946.7 4953.8 4043.8 4496.4 4788.5 4879.2 4925.3 4551.4 4554.8 4765.7 4956.4 4958.4 4186.6 4548.5 4712.1 4779.6 4862.7 3778.4 4324.6 4415.6 4665.1 4692.2 4580.5 4723.7 4741.5 4892.5 5036.5 3062.3 3232.2 3310.5 3365.7 3370.3 4281.5 4288.9 4714.1 4770.7 5487.7 4875.0 5416.4 5605.2 5625.0 5644.2 5629.6 5632.3 5639.3 5657.6 5666.9 5192.8 5480.1 5493.3 5507.6 5561.4 5599.6 5619.9 5621.3 5630.9 5665.4 4935.6 5447.9 5485.6 5525.0 5556.9 12:17:17 INFO - PROCESS | 1662 | 12:17:17 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:26228.34 Median:3888.12 stddev:43701.83 (1124.0%) stddev-sans-first:43782.22 12:17:17 INFO - PROCESS | 1662 | Values: 648.6 717.2 764.8 792.5 814.6 15106.9 15926.9 15933.1 15937.3 16023.0 14162.4 14264.0 14277.4 14283.4 14736.5 668.0 687.5 806.9 828.2 841.0 836.7 851.7 854.7 855.0 855.5 899.4 901.5 901.9 902.4 904.2 709.8 879.4 897.9 897.9 898.8 661.5 667.0 671.6 672.4 672.9 14891.2 14939.6 14939.9 14973.7 15053.8 13756.4 13890.5 14084.9 14151.6 14303.3 12777.4 12792.5 12843.4 12933.5 13106.2 4380.4 4399.4 4428.3 4524.4 4525.5 114606.0 115050.0 115337.0 116186.0 118193.4 10847.5 11044.8 11105.9 11170.9 11249.0 21847.0 21954.1 21962.4 21984.9 22001.2 7569.8 7727.2 7757.0 7865.7 8080.4 4313.8 4333.9 4395.3 4411.0 4540.7 3398.4 3549.9 3784.2 3992.0 3992.1 717.0 724.6 732.1 735.2 739.1 619.5 635.0 645.7 649.5 652.5 9160.0 9430.0 9514.7 9544.6 9613.7 526.8 578.4 586.4 600.2 615.2 4427.7 4624.2 4645.4 4667.1 4831.5 155865.2 161733.4 166812.2 167509.0 172057.0 141067.0 146123.0 146760.3 147919.3 149666.0 120565.0 121501.4 123232.2 125363.0 126571.4 8350.9 8909.6 8942.2 9156.6 9174.5 46374.8 54860.5 64295.7 65949.0 66112.7 85896.0 97609.0 98542.0 98572.0 101206.0 11367.4 11877.4 11971.9 12047.8 12105.8 12953.0 13459.7 13558.9 13561.5 13841.8 11895.9 11986.5 12501.6 12639.7 12768.0 12071.3 12309.8 12551.9 12617.3 12682.5 12183.5 12215.2 12442.6 12477.3 12591.3 9925.9 10466.7 10532.9 10724.1 10761.2 8341.1 9476.7 10043.9 10202.0 10475.1 12:17:17 INFO - PROCESS | 1662 | -------- Summary: end -------- 12:17:17 INFO - PROCESS | 1662 | 12:17:17 INFO - TEST-INFO | 1662: exit 0 12:17:17 INFO - TEST-OK | dromaeo_css | took 1117163ms 12:17:17 INFO - TEST-START | kraken 12:17:17 INFO - operating with platform_type : mac_ 12:17:17 INFO - Initialising browser for kraken test... 12:17:17 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpa7k6Bu/profile http://localhost:49277/getInfo.html) 12:17:20 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 12:17:20 INFO - PROCESS | 1669 | colorDepth:24 12:17:20 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 12:17:20 INFO - PROCESS | 1669 | __metrics 12:17:20 INFO - PROCESS | 1669 | 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" 12:17:20 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:17:20 INFO - TEST-INFO | 1669: exit 0 12:17:20 INFO - Browser initialized. 12:17:20 INFO - Running cycle 1/1 for kraken test... 12:17:20 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpa7k6Bu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:17:32 INFO - PROCESS | 1670 | RSS: Main: 244768768 12:17:32 INFO - PROCESS | 1670 | 12:17:35 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 12:17:36 INFO - PROCESS | 1670 | RSS: Main: 294117376 12:17:36 INFO - PROCESS | 1670 | 12:17:39 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 12:17:39 INFO - PROCESS | 1670 | RSS: Main: 290029568 12:17:39 INFO - PROCESS | 1670 | 12:17:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 12:17:43 INFO - PROCESS | 1670 | RSS: Main: 378146816 12:17:43 INFO - PROCESS | 1670 | 12:17:46 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 12:17:46 INFO - PROCESS | 1670 | RSS: Main: 281747456 12:17:46 INFO - PROCESS | 1670 | 12:17:48 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:17:49 INFO - PROCESS | 1670 | RSS: Main: 285241344 12:17:49 INFO - PROCESS | 1670 | 12:17:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 12:17:52 INFO - PROCESS | 1670 | RSS: Main: 323604480 12:17:52 INFO - PROCESS | 1670 | 12:17:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 12:17:56 INFO - PROCESS | 1670 | RSS: Main: 324435968 12:17:56 INFO - PROCESS | 1670 | 12:17:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 12:18:00 INFO - PROCESS | 1670 | RSS: Main: 325505024 12:18:00 INFO - PROCESS | 1670 | 12:18:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:18:02 INFO - PROCESS | 1670 | RSS: Main: 298545152 12:18:02 INFO - PROCESS | 1670 | 12:18:04 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:18:04 INFO - PROCESS | 1670 | RSS: Main: 304545792 12:18:04 INFO - PROCESS | 1670 | 12:18:06 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:18:07 INFO - PROCESS | 1670 | RSS: Main: 302333952 12:18:07 INFO - PROCESS | 1670 | 12:18:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:18:11 INFO - PROCESS | 1670 | RSS: Main: 322908160 12:18:11 INFO - PROCESS | 1670 | 12:18:14 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:18:14 INFO - PROCESS | 1670 | RSS: Main: 318918656 12:18:14 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 12:18:16 INFO - PROCESS | 1670 | RSS: Main: 362369024 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | __start_tp_report 12:18:16 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 12:18:16 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 12:18:16 INFO - PROCESS | 1670 | |i|pagename|runs| 12:18:16 INFO - PROCESS | 1670 | |0;ai-astar;113;114;113;114;113;112;122;159;166;111 12:18:16 INFO - PROCESS | 1670 | |1;audio-beat-detection;146;112;129;161;110;131;130;108;164;107 12:18:16 INFO - PROCESS | 1670 | |2;audio-dft;187;187;185;183;184;184;185;185;184;181 12:18:16 INFO - PROCESS | 1670 | |3;audio-fft;73;76;75;152;59;58;66;69;140;58 12:18:16 INFO - PROCESS | 1670 | |4;audio-oscillator;83;81;130;70;80;87;115;67;71;112 12:18:16 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;93;89;92;89;91;88;92;88;91;89 12:18:16 INFO - PROCESS | 1670 | |6;imaging-darkroom;139;148;128;97;97;115;147;149;96;97 12:18:16 INFO - PROCESS | 1670 | |7;imaging-desaturate;131;134;99;83;85;84;85;85;83;84 12:18:16 INFO - PROCESS | 1670 | |8;json-parse-financial;55;55;57;55;55;54;55;54;54;54 12:18:16 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;67;67;73;75;76;78;73;71;96;104 12:18:16 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;86;88;83;86;84;85;84;85;87;95 12:18:16 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;140;138;134;139;137;137;134;132;183;151 12:18:16 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;168;170;169;167;173;170;164;167;168;168 12:18:16 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;66;67;67;68;68;67;67;66;63;66 12:18:16 INFO - PROCESS | 1670 | __end_tp_report 12:18:16 INFO - PROCESS | 1670 | __start_cc_report 12:18:16 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2083 12:18:16 INFO - PROCESS | 1670 | __end_cc_report 12:18:16 INFO - PROCESS | 1670 | __startTimestamp1473189496647__endTimestamp 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | ------- Summary: start ------- 12:18:16 INFO - PROCESS | 1670 | Number of tests: 14 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:123.70 Median:114.00 stddev:20.73 (18.2%) stddev-sans-first:21.62 12:18:16 INFO - PROCESS | 1670 | Values: 113.0 114.0 113.0 114.0 113.0 112.0 122.0 159.0 166.0 111.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:130.50 stddev:21.37 (16.4%) stddev-sans-first:21.85 12:18:16 INFO - PROCESS | 1670 | Values: 146.0 112.0 129.0 161.0 110.0 131.0 130.0 108.0 164.0 107.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.78 (1.0%) stddev-sans-first:1.64 12:18:16 INFO - PROCESS | 1670 | Values: 187.0 187.0 185.0 183.0 184.0 184.0 185.0 185.0 184.0 181.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:82.60 Median:67.50 stddev:34.21 (50.7%) stddev-sans-first:36.11 12:18:16 INFO - PROCESS | 1670 | Values: 73.0 76.0 75.0 152.0 59.0 58.0 66.0 69.0 140.0 58.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:75.50 stddev:21.70 (28.7%) stddev-sans-first:22.88 12:18:16 INFO - PROCESS | 1670 | Values: 83.0 81.0 130.0 70.0 80.0 87.0 115.0 67.0 71.0 112.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:91.00 stddev:1.81 (2.0%) stddev-sans-first:1.62 12:18:16 INFO - PROCESS | 1670 | Values: 93.0 89.0 92.0 89.0 91.0 88.0 92.0 88.0 91.0 89.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:121.30 Median:122.50 stddev:23.45 (19.1%) stddev-sans-first:23.98 12:18:16 INFO - PROCESS | 1670 | Values: 139.0 148.0 128.0 97.0 97.0 115.0 147.0 149.0 96.0 97.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:84.50 stddev:20.17 (23.9%) stddev-sans-first:16.76 12:18:16 INFO - PROCESS | 1670 | Values: 131.0 134.0 99.0 83.0 85.0 84.0 85.0 85.0 83.0 84.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.92 (1.7%) stddev-sans-first:0.97 12:18:16 INFO - PROCESS | 1670 | Values: 55.0 55.0 57.0 55.0 55.0 54.0 55.0 54.0 54.0 54.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:78.00 Median:74.00 stddev:12.27 (16.6%) stddev-sans-first:12.35 12:18:16 INFO - PROCESS | 1670 | Values: 67.0 67.0 73.0 75.0 76.0 78.0 73.0 71.0 96.0 104.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:86.00 stddev:3.40 (4.0%) stddev-sans-first:3.61 12:18:16 INFO - PROCESS | 1670 | Values: 86.0 88.0 83.0 86.0 84.0 85.0 84.0 85.0 87.0 95.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:138.50 stddev:15.15 (10.9%) stddev-sans-first:16.05 12:18:16 INFO - PROCESS | 1670 | Values: 140.0 138.0 134.0 139.0 137.0 137.0 134.0 132.0 183.0 151.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:168.50 stddev:2.37 (1.4%) stddev-sans-first:2.51 12:18:16 INFO - PROCESS | 1670 | Values: 168.0 170.0 169.0 167.0 173.0 170.0 164.0 167.0 168.0 168.0 12:18:16 INFO - PROCESS | 1670 | 12:18:16 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.43 (2.1%) stddev-sans-first:1.51 12:18:16 INFO - PROCESS | 1670 | Values: 66.0 67.0 67.0 68.0 68.0 67.0 67.0 66.0 63.0 66.0 12:18:16 INFO - PROCESS | 1670 | -------- Summary: end -------- 12:18:16 INFO - PROCESS | 1670 | 12:18:17 INFO - TEST-INFO | 1670: exit 0 12:18:17 INFO - TEST-OK | kraken | took 59627ms 12:18:17 INFO - SUITE-END | took 1176s 12:18:17 INFO - Completed test suite (00:19:37) 12:18:17 INFO - javascript benchmark 12:18:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2663.9467210655785, 3064.187990182602, 3079.7382222511087, 3315.2043509557707, 3372.207531230159, 5612.186232996223, 5689.288838895138, 5699.116636921273, 5754.568407369448, 5787.56593255506, 4693.342931989524, 5724.599278050528, 5813.941860581393, 5822.7088645567765, 5858.297004359472, 5144.4598317176715, 5148.948510514903, 5212.192110222922, 5237.79048838046, 5404.540614047791, 5282.943411317732, 5331.6534425262425, 5341.572674186055, 5345.732713364336, 5362.490563396471, 5150.150225212836, 5154.74226288686, 5159.5614372778355, 5203.089459344599, 5238.345206849144, 5193.221016847466, 5212.791488340462, 5220.373555173365, 5265.973670131663, 5269.841904742864, 5173.146430838907, 5192.402873669538, 5211.921821172686, 5229.869253268661, 5233.058049551079, 5596.748146333425, 5642.858928526779, 5698.34469036062, 5780.161876527922, 5804.680742559158, 6197.504199664016, 6239.157713708637, 6269.8746025079245, 6279.560430769848, 6285.402886725754, 6264.404881536247, 6268.0, 6275.592086514362, 6275.843103922392, 6277.748890044393, 5701.486866182064, 5736.77052917887, 5747.080467125324, 5795.82612521625, 5817.680027598481, 6202.782902598477, 6238.688065596702, 6255.655938923312, 6259.561830671809, 6264.0, 6124.050772130327, 6141.293751218667, 6150.3849615039035, 6152.446279834837, 6162.106494558264, 6114.449699527064, 6135.110179431455, 6197.659128747962, 6272.749090036439, 6276.717547710364, 6100.944524685232, 6115.666295175888, 6153.800008998202, 6229.0967809667345, 6247.9062814057825, 6251.562390632612, 6253.3121356651645, 6265.373462653698, 6278.246610406689, 6305.085762564402, 4815.374001379798, 4982.701037937735, 4993.50064993505, 4995.375578052744, 5021.995600879839, 4399.38408622793, 4402.625776809007, 4466.151431228056, 4492.483364413069, 4548.613367863769, 5673.262475878109, 5674.290713660793, 5683.2895888014, 5691.4877661010705, 5695.686737229492, 6236.064590311399, 6308.369163083655, 6323.146375239376, 6325.5255855811, 6329.556931014877, 5087.262346959744, 5534.363548192009, 5549.417311182267, 5589.552835773128, 5643.068893632587, 5387.461253874581, 5462.426445223274, 5478.506934375925, 5525.502704756511, 5539.723013849372, 6249.7500099997305, 6272.717727702265, 6280.685965701605, 6283.2460104787715, 6287.748490060347, 6259.405356491126, 6265.373462653698, 6276.623402595859, 6306.337834527492, 6315.684215789101, 6211.0372892201785, 6216.191895053614, 6235.220597425322, 6258.812235632939, 6319.494440444661, 6210.627362358092, 6211.2857021441305, 6219.346968568235, 6232.127502149793, 6233.252009758858, 6156.6921653918025, 6274.623522588659, 6300.7479700811455, 6314.179156709598, 6319.304876463677, 5960.910586341121, 6032.698365081641, 6066.878662426639, 6108.297545782292, 6146.692665366624, 6100.163796098639, 6104.822969073627, 6106.399996025681, 6107.735039974475, 6127.9837278401355, 5932.466078052996, 5954.285485741739, 5972.223610930551, 5977.252843394575, 6004.669743163993, 5903.498202652822, 6024.7288872001745, 6094.939050609437, 6107.54193435503, 6121.788327166137, 5872.0, 5933.762649494144, 5949.1076338549565, 5953.851153721192, 5996.130561068708], "value": 5692.184630961606, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.03505226654687, 474.8884012257119, 494.82186412891355, 536.8738346488577, 554.2849723856226, 29675.703242967564, 30285.24286892826, 38909.416358754606, 40005.7999710001, 40118.79940600293, 40489.797551012205, 40803.79598102013, 40813.183736325256, 40819.0, 40972.59027409725, 9596.760080997996, 9750.36622619531, 9753.073458021476, 9759.975202603731, 9797.363171393872, 8356.623951922178, 9606.90393096067, 9614.471204083773, 9615.903840961571, 9649.324547281693, 9641.228701703847, 9674.75813104671, 9679.128878400943, 9708.271879609023, 9710.12608865202, 9583.329166958316, 9637.277204209677, 9638.951805240999, 9647.131758141732, 9662.08210220028, 8353.95823020881, 8431.451955622926, 9624.855627165598, 9626.037396260388, 9630.374025688308, 38502.8449146526, 38819.0, 38823.61176388228, 38846.80576597099, 38882.80558597189, 3533.222691007974, 3584.211473475857, 3603.819809009539, 3620.203555217874, 3861.3242682530445, 29646.0, 29745.851270743507, 30678.9262375818, 30763.923262685923, 31088.22279443032, 3223.097532690827, 3301.6533264007176, 3312.602487701467, 3344.4481660525985, 3348.5312056312137, 440664.0, 444331.0, 445606.77196614136, 445779.0, 445844.77077614405, 32499.837500812344, 54671.45328546664, 54697.1795423069, 55144.0, 55144.79420823202, 32956.83521582377, 34440.655593444244, 34453.655463445546, 34462.31075378478, 34501.48247776285, 12390.0, 14332.426702931765, 14452.843772498174, 14496.637584060483, 14513.346899389553, 12380.0, 12462.43919023646, 12475.126741128033, 12476.18904771187, 12491.562795302121, 6614.437772789271, 6647.302033286532, 6660.433863121689, 7709.267619576131, 7721.189275126143, 2881.250874772574, 3191.457452233126, 3254.560634314337, 3256.362346499343, 3289.292802047571, 805.367786287773, 940.8118376324762, 987.3138168972495, 1031.8194315994672, 1045.3153184664056, 28861.56707649387, 29357.559636605467, 29390.559141612892, 29966.70033299639, 30052.398952020838, 918.0177210384824, 1030.1501261459177, 1032.2052019944601, 1048.5805677728817, 1212.5755985405037, 12850.67873303175, 14593.562193134223, 15158.090514569161, 15209.847901521065, 15594.064356138668, 587970.0, 588097.0, 589572.0, 593369.0, 593467.0, 54832.0, 55237.0, 55288.17067743987, 55426.72286638541, 55470.0, 1194.2416565480808, 1252.0922331309728, 1370.4449697872178, 1392.5822253323963, 1412.9081609695336, 30010.399792004475, 30144.547831782103, 30318.848405758712, 30892.38215235638, 32306.515402268513, 53865.461345387615, 54623.726881365335, 54659.72670136783, 54686.0, 54822.45177548333, 606802.0, 607666.0, 608366.0, 612419.0, 612454.0, 34540.48189277213, 40494.392584110676, 41239.96900077402, 41366.37950430806, 41519.792401037805, 45399.0, 45658.54341456676, 46207.53792462032, 46407.071858563315, 47044.7647761759, 37584.060398490255, 37596.62403375931, 37805.961629147074, 38469.0, 39099.60900391074, 37546.0, 38109.0, 38750.80624596859, 39018.80490597529, 39181.0, 36570.63429365672, 36704.0, 36791.26417471689, 37360.813195934934, 37645.0, 28076.85961570179, 31237.062888113393, 32297.031089067365, 33764.66235337615, 33830.0, 24418.755812441646, 24526.0, 24632.78304952491, 25287.620685689362, 25557.23328300154], "value": 18447.490617112257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.78470886278365, 350.1910586545081, 351.4096318185449, 356.4243746349645, 381.7079933850605, 15359.616009599766, 15532.45636402726, 16069.75895361569, 16342.754858677099, 17260.0, 17338.739918901196, 19940.9002954985, 20110.0, 20356.0, 20530.589388212225, 5625.718714064291, 5934.228550288456, 5946.375630558794, 5946.881062378749, 5951.642901425928, 5353.0, 5802.506786923117, 5862.0, 5872.442117998805, 5944.2866855977345, 5879.32387775407, 5882.323532793719, 5883.646981181121, 5887.8528036799, 5891.734871930752, 5618.381977982419, 5746.683932383718, 5799.36207017232, 5803.767849286023, 5812.070068788974, 5025.773840177201, 5031.77357018935, 5036.345275114249, 5041.2438134280155, 5050.823221187277, 13409.195448273134, 13626.386812593359, 22786.088556457722, 24449.87775061131, 25552.48895022108, 2018.9495262618552, 2086.2802333195177, 2411.9035238590436, 2418.322869596516, 2637.406583518712, 9857.0, 9953.30326877119, 10308.381497110197, 10320.058783246814, 10327.58689652413, 1805.873588848781, 1807.8591793727533, 1857.2942281932915, 1858.7397764313007, 1932.5168707823045, 107034.0, 119574.80425195723, 121798.0, 121855.78144218533, 122879.0, 9547.467437048337, 10684.572617095384, 11327.0, 11327.433628318717, 11367.612801311652, 14170.024294898187, 14171.78742318866, 14187.361568729431, 14188.85811141896, 14198.929005355114, 8231.382646301552, 8504.659813607386, 8525.914740852637, 8660.13398660129, 8660.8700869487, 8465.703700370457, 8466.619002144918, 8473.57632118404, 8666.913330866737, 8680.175383338572, 4413.602775750133, 4462.330650402466, 4475.149721552894, 4554.931676024862, 4555.681102322872, 1849.1216672080654, 1900.3063881683086, 1907.7519922409997, 1908.799576044523, 1913.5120544261124, 714.5212707485997, 777.9769602972186, 841.5329492131925, 868.2359523619175, 869.699953516036, 19645.607087858447, 20729.0671919764, 21184.65714528518, 21450.0, 22514.887425563094, 845.159066728609, 894.9015608283083, 948.6015873333216, 961.0, 1024.2164743970868, 9695.127438530566, 10500.159987201005, 10736.838947415796, 11250.381229032319, 11253.549858005588, 563652.0, 565709.0, 588832.0, 597882.0, 604701.0, 92582.0, 128065.0, 133403.99894001597, 135332.0, 139001.0, 936.3118108190474, 987.3828856964398, 1000.4047591682792, 1007.8588997540351, 1016.5171543516918, 18689.065546722337, 18818.0, 19515.414537563895, 19676.704849426984, 19687.917164555514, 54158.916821662555, 54929.45070549243, 55158.0, 55869.72065139811, 57197.42802571921, 75509.93161353428, 82355.17644823475, 83725.0, 85743.14256857352, 85882.0, 6843.0, 7530.736424225126, 7918.841623167619, 7998.48009879356, 8225.424220304521, 12476.0, 12636.620901372973, 13065.804012939623, 13110.213387196798, 13125.934370328087, 11028.338299702044, 11195.384253866116, 11203.775924481628, 11227.49476273586, 11328.037116844831, 10966.561337546409, 11188.042092633594, 11290.49192786343, 11502.252353596989, 11517.654470365902, 11313.377764223047, 11346.602868899548, 11411.315321080761, 11438.542458301576, 11460.94269528647, 7746.760518317043, 7940.801479963047, 7967.721129760431, 8203.466774659628, 8241.95879020601, 8554.957225213833, 10814.837777433502, 11131.053860421955, 11155.219134660496, 11174.217804753589], "value": 9341.223606456579, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1485.3835658201847, 1498.1760031982408, 1711.2203308559099, 1884.3404808317082, 1920.932767353143, 7854.0, 8192.221738934792, 8394.706185283516, 8919.15268049536, 8928.642854285828, 8331.667133202698, 8648.437851539646, 8653.091425400336, 8750.168733970278, 8755.430896991707, 2262.298687406901, 2295.82781291403, 2299.241250387368, 2300.5858945389823, 2303.884805759714, 2018.354126679456, 2098.590774798915, 2257.5033492631596, 2288.0733303012307, 2311.05246848792, 2071.989640051805, 2089.7387826521685, 2204.9779502204933, 2285.714285714286, 2290.7938285554296, 2275.1581914691506, 2282.3153054083873, 2282.9200977965857, 2285.325828880484, 2302.4474126209757, 2239.3617818921693, 2269.7730226977337, 2280.817534597228, 2283.942901427461, 2289.6565515172692, 8660.913390866137, 8843.690470833555, 8845.601947912359, 8856.690015849414, 8858.29133669305, 4955.355803745526, 5063.974680126576, 5085.415177254626, 5091.821786237464, 5099.286099946011, 3315.53582498448, 3319.9834000830083, 3370.5112758650102, 3398.796072235674, 3455.671711187433, 1009.0918173643706, 1012.9797404051951, 1026.296986564206, 1039.7868436970477, 1041.468850886046, 11409.543618255177, 11413.233632901205, 11429.42852857362, 11429.542818287258, 11432.771344573062, 4206.768627725504, 4265.786710664454, 4355.433793606874, 4434.13534360803, 4446.555344465528, 8809.515476648847, 9972.351797133164, 9977.900220997843, 9980.351277166961, 9983.35108217978, 5095.745212739348, 5104.3619547556555, 5108.846734597893, 5112.182050871843, 5122.410922743858, 4548.795304211319, 4600.378948841931, 4689.366935463669, 4695.272232803921, 4698.154332220165, 3667.1198912260866, 3675.9264814703556, 3691.9261614767556, 3776.6223377662013, 3824.349860523718, 1119.0, 1131.2364154195884, 1144.7710457908452, 1158.449736375215, 1158.6987383280407, 601.0983524712931, 744.0624812735991, 870.9085546017703, 876.4609764994483, 940.5062342270226, 5636.464535869086, 5768.0, 5917.467427931507, 6013.128096425994, 6028.487578555784, 873.9431936924207, 887.8401887660154, 891.8138875295841, 950.7385468995943, 959.3380567408466, 3881.3983832506005, 3923.6861051115843, 3933.9016524587114, 4039.2325458162854, 4056.9594304057173, 10371.896281037243, 10702.892971070345, 10764.623238186627, 10777.676669699922, 10797.406142662072, 10715.571377144983, 10739.624113156002, 10739.731506712395, 10757.78484430307, 10760.515776790064, 6551.934480655133, 7077.256888026683, 8163.755087347389, 8192.54940978252, 8262.504249745034, 5401.945980540144, 5985.132155837467, 6089.147519347383, 6098.237720284965, 6186.659733714689, 9608.327417080738, 10007.049330313608, 10031.0, 10149.949250253701, 10188.28681992283, 9830.050984704703, 10154.0, 10339.121174700234, 10381.27331086847, 10386.42874641902, 7344.70621175147, 7568.356689681439, 7649.732259370895, 7658.119316278699, 7678.347340476123, 8259.628316725883, 8336.833263334578, 8343.0, 8344.7913802153, 8348.081711011662, 7110.146782386147, 7446.180920098893, 7459.738909138154, 7538.434617403607, 7645.541267523967, 7275.599842008741, 7381.704731810667, 7388.224236455095, 7469.066366704162, 7469.14104877946, 6929.688164032731, 7037.1555413350725, 7046.647667616701, 7054.823629409306, 7057.470689698191, 4200.6219440250525, 4925.876853078702, 5030.798768049383, 5059.822906198266, 5129.999650068201, 4916.901661966812, 5044.4451110377095, 5105.642605017613, 5129.204973229155, 5230.712310822942], "value": 4487.529537816795, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [238.41944864255538, 276.3850432787049, 285.75996163222896, 288.96676882158545, 291.5946833900877, 4694.342792009113, 4728.952710472894, 4748.145333839908, 5394.5954053446, 5397.4332695067, 4695.647826413024, 5162.741862906859, 5241.475852414756, 5346.893062138755, 5409.594280428964, 3439.8452069656923, 4099.180163967204, 4106.958930410702, 4126.607972242639, 4138.6482149017365, 3633.3278343506404, 3691.575468821093, 3790.469334293201, 3926.155876486555, 4132.0, 3446.3968805458944, 3808.752431091984, 3936.822842972059, 4103.651189648868, 4112.773797441139, 3421.5209870618132, 4066.4510291110764, 4072.7352722073265, 4082.2447847148374, 4083.325836407059, 3191.9042428726943, 3400.401811148781, 3571.0715214044535, 3573.535440392758, 3596.8741094061584, 4744.905101897977, 5023.7236951967625, 5112.539871411591, 5248.291480650101, 5277.551408130314, 3789.905252368685, 4667.579917807379, 4670.579647831712, 4671.346011558385, 4688.554587314234, 5016.147254966664, 5537.556995440355, 5575.539568345307, 5587.413321601214, 5625.212470254168, 3734.0104872208613, 3754.7758771382196, 3826.215625796705, 3870.148567315186, 3873.283442563135, 4640.86077417678, 5232.319798426256, 5921.83852893695, 5996.700164991733, 6002.96998515009, 3937.9409308859817, 3990.620891015349, 4003.979980100081, 4006.358982562776, 4006.579309172553, 3862.1889403225637, 4022.0, 4026.617471340218, 4046.6560342371226, 4049.3318602430268, 3869.7616762635685, 4064.5570822358227, 4610.792514336863, 4662.510436404129, 4688.718676879398, 5200.583953283728, 5600.963821692918, 5642.2479051431155, 5650.29371328584, 5673.404292549306, 1171.6467480060544, 1203.1577895473204, 1234.5370486067725, 1268.2517314784322, 1350.216874212954, 852.9402941794139, 869.8303830752942, 880.3925291548811, 937.8874535055701, 938.2869019545195, 4614.531225794184, 4885.802978270275, 5054.292399064134, 5247.2653828464045, 5258.789648414021, 4658.394408726844, 5124.948750512522, 5753.395893431213, 5813.680247586339, 5827.650340979555, 4391.407160033418, 4847.3698419205275, 4863.829765958245, 4931.4821943696115, 5189.610779191576, 2248.1681777742015, 2385.7375688674233, 2524.9521448598994, 2532.7593878581506, 2536.4546622475887, 4303.612674859278, 4611.469680986663, 4930.753462326869, 4946.678465899705, 4953.777080031407, 4043.838246470167, 4496.392986946786, 4788.545088216614, 4879.219324908068, 4925.335079764186, 4551.362809206714, 4554.76611605757, 4765.69023013503, 4956.3804524434445, 4958.42978057528, 4186.623203911662, 4548.476260452785, 4712.057588482386, 4779.569838714532, 4862.6839255448285, 3778.4332350147697, 4324.56754324565, 4415.624671902924, 4665.136949664323, 4692.202325604723, 4580.450345958507, 4723.6929599575915, 4741.5021422750115, 4892.535209155125, 5036.496350364934, 3062.3262882165886, 3232.2404235005224, 3310.470324748125, 3365.663433656615, 3370.292238629817, 4281.507626622978, 4288.871333859989, 4714.114354258324, 4770.6843743597865, 5487.725613719218, 4875.0, 5416.431274716098, 5605.1872478491205, 5625.0, 5644.2380278663495, 5629.634073785191, 5632.324121105494, 5639.251831932114, 5657.575681823797, 5666.886662266814, 5192.818251361185, 5480.095784195722, 5493.285872836505, 5507.614466987433, 5561.416051314553, 5599.580031497572, 5619.8595035124445, 5621.269234999424, 5630.943690563042, 5665.376808551138, 4935.605151587936, 5447.945520544744, 5485.6434331768305, 5524.961271300849, 5556.888862222814], "value": 3887.8790309314836, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.5687018132942, 717.2433083097337, 764.7705688293511, 792.4928046050528, 814.645629151319, 15106.924465377671, 15926.920365398157, 15933.123678197695, 15937.282822273011, 16023.0, 14162.433502659907, 14264.0, 14277.428902843874, 14283.357248923798, 14736.484223052195, 667.9666016699146, 687.4740823270203, 806.9112397636255, 828.2214718164936, 841.0286119531951, 836.6778790165799, 851.7317045130795, 854.6666799948025, 855.0081904990212, 855.4738835616082, 899.3614533681094, 901.5447199164413, 901.8602116671926, 902.4314681750488, 904.2314033071902, 709.8473828126926, 879.4415546128255, 897.9012308646011, 897.9281657467419, 898.8382091223642, 661.5369241530899, 667.0494545273015, 671.5500614588188, 672.4317951331118, 672.8586996730693, 14891.180985045818, 14939.551813445614, 14939.850601493954, 14973.700525989529, 15053.774193387108, 13756.449742010307, 13890.513832015831, 14084.92957535216, 14151.575452736435, 14303.28483575817, 12777.425015874307, 12792.48830046797, 12843.422046007952, 12933.48266069356, 13106.2136271824, 4380.364847097185, 4399.36209249657, 4428.2914733642465, 4524.411826462571, 4525.5021947586065, 114606.0, 115050.0, 115337.0, 116186.0, 118193.40903295514, 10847.51186196623, 11044.779104417867, 11105.888941110647, 11170.888291117148, 11249.0, 21847.0, 21954.121835126414, 21962.364516257832, 21984.89007554984, 22001.22995695175, 7569.848603027908, 7727.1886451922865, 7756.961215194001, 7865.729960926194, 8080.353571714249, 4313.784310784511, 4333.92892594801, 4395.25280702275, 4411.0, 4540.704854184533, 3398.4052790761516, 3549.8757543485854, 3784.2242340320167, 3992.0219546210783, 3992.0818211811447, 716.9749058782918, 724.644923987253, 732.1287667675449, 735.1582438108443, 739.0577014306801, 619.5322531488697, 634.9968250158812, 645.7258977617147, 649.5420728386476, 652.4539129829298, 9159.95420022909, 9429.952850235704, 9514.672742626215, 9544.570494327772, 9613.663521776843, 526.8129813916081, 578.4302462074853, 586.4194447497033, 600.1627729317578, 615.2217444932164, 4427.690061695715, 4624.237000894815, 4645.419322584677, 4667.113248482778, 4831.4685384607665, 155865.2206738959, 161733.38266617182, 166812.16593917194, 167509.0, 172057.0, 141067.0, 146123.0, 146760.2661986683, 147919.26040369945, 149666.0, 120565.0, 121501.3924930405, 123232.15151772731, 125363.0, 126571.36714316554, 8350.874736879074, 8909.643614255616, 8942.239909607757, 9156.633734650806, 9174.541272936192, 46374.76812615915, 54860.45139548713, 64295.67852160709, 65949.0, 66112.67774644577, 85896.0, 97609.0, 98542.0, 98572.0, 101206.0, 11367.374794386387, 11877.406129693309, 11971.880281197426, 12047.759044818878, 12105.81841272364, 12953.0, 13459.663508412368, 13558.864411356155, 13561.45754169822, 13841.792373114613, 11895.940520297343, 11986.520539178335, 12501.624951251477, 12639.747205056032, 12768.0, 12071.275723456622, 12309.75380492403, 12551.87448125507, 12617.306048167438, 12682.492700291885, 12183.451744671689, 12215.206011609216, 12442.626721198378, 12477.251364918135, 12591.307478088793, 9925.900740992498, 10466.744227318342, 10532.89467105319, 10724.088452481627, 10761.246712730346, 8341.09531043748, 9476.715698529055, 10043.949780251052, 10201.97980201974, 10475.109615682752], "value": 7817.2604957605645, "unit": "score"}], "name": "dromaeo_css", "value": 7151.658724341288, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 113.0, 112.0, 122.0, 159.0, 166.0, 111.0], "value": 123.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 129.0, 161.0, 110.0, 131.0, 130.0, 108.0, 164.0, 107.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 185.0, 183.0, 184.0, 184.0, 185.0, 185.0, 184.0, 181.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 75.0, 152.0, 59.0, 58.0, 66.0, 69.0, 140.0, 58.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 130.0, 70.0, 80.0, 87.0, 115.0, 67.0, 71.0, 112.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 92.0, 89.0, 91.0, 88.0, 92.0, 88.0, 91.0, 89.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 148.0, 128.0, 97.0, 97.0, 115.0, 147.0, 149.0, 96.0, 97.0], "value": 121.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 134.0, 99.0, 83.0, 85.0, 84.0, 85.0, 85.0, 83.0, 84.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 57.0, 55.0, 55.0, 54.0, 55.0, 54.0, 54.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 73.0, 75.0, 76.0, 78.0, 73.0, 71.0, 96.0, 104.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 83.0, 86.0, 84.0, 85.0, 84.0, 85.0, 87.0, 95.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 134.0, 139.0, 137.0, 137.0, 134.0, 132.0, 183.0, 151.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 169.0, 167.0, 173.0, 170.0, 164.0, 167.0, 168.0, 168.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 68.0, 68.0, 67.0, 67.0, 66.0, 63.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1513.5, "alertThreshold": 2.0}]} 12:18:17 INFO - Return code: 0 12:18:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:18:17 INFO - # TBPL SUCCESS # 12:18:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:18:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:18:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:18:17 INFO - 2016-09-06 12:18:17.970 screenresolution[1674:10048] starting screenresolution argv=screenresolution get 12:18:17 INFO - 2016-09-06 12:18:17.972 screenresolution[1674:10048] Display 0: 1600x1200x32@60 12:18:17 INFO - 2016-09-06 12:18:17.978 screenresolution[1675:10051] starting screenresolution argv=screenresolution list 12:18:17 INFO - Available Modes on Display 0 12:18:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:18:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:18:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:18:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:18:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:18:18 INFO - Intel Iris: 12:18:18 INFO - Chipset Model: Intel Iris 12:18:18 INFO - Type: GPU 12:18:18 INFO - Bus: Built-In 12:18:18 INFO - VRAM (Dynamic, Max): 1536 MB 12:18:18 INFO - Vendor: Intel (0x8086) 12:18:18 INFO - Device ID: 0x0a2e 12:18:18 INFO - Revision ID: 0x0009 12:18:18 INFO - Displays: 12:18:18 INFO - Display: 12:18:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:18:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:18:18 INFO - Display Serial Number: 1600x1200 60 12:18:18 INFO - Main Display: Yes 12:18:18 INFO - Mirror: Off 12:18:18 INFO - Online: Yes 12:18:18 INFO - Rotation: Supported 12:18:18 INFO - Return code: 0 12:18:18 INFO - Running post-action listener: _resource_record_post_action 12:18:18 INFO - [mozharness: 2016-09-06 19:18:18.213710Z] Finished run-tests step (success) 12:18:18 INFO - Running post-run listener: _resource_record_post_run 12:18:18 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 32517120; Write bytes: 706714112; Read time: 313; Write time: 1999 12:18:18 INFO - TinderboxPrint: CPU usage
53.8% 12:18:18 INFO - TinderboxPrint: I/O read bytes / time
32,517,120 / 313 12:18:18 INFO - TinderboxPrint: I/O write bytes / time
706,714,112 / 1,999 12:18:18 INFO - TinderboxPrint: CPU idle
2,242.5 (46.2%) 12:18:18 INFO - TinderboxPrint: CPU system
974.7 (20.1%) 12:18:18 INFO - TinderboxPrint: CPU user
1,636.5 (33.7%) 12:18:18 INFO - TinderboxPrint: Swap in / out
434,716,672 / 0 12:18:18 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 407228928; Write bytes: 409634816; Read time: 31517; Write time: 799 12:18:18 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29219840; Write bytes: 295502336; Read time: 285; Write time: 1195 12:18:18 INFO - Running post-run listener: _upload_blobber_files 12:18:18 INFO - Blob upload gear active. 12:18:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:18:18 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 12:18:18 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:18:18 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:18:19 INFO - (blobuploader) - INFO - Open directory for files ... 12:18:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:18:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:18:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:20 INFO - (blobuploader) - INFO - Done attempting. 12:18:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:18:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:18:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:21 INFO - (blobuploader) - INFO - Done attempting. 12:18:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:18:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:18:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:23 INFO - (blobuploader) - INFO - Done attempting. 12:18:23 INFO - (blobuploader) - INFO - Iteration through files over. 12:18:23 INFO - Return code: 0 12:18:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:18:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:18:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b806c76820ed708e180e0453514b58bd118f59c2b914291aa34c13c3d7ff92b75533d051fb9a3f3d82018551d47d603cba58f35b01088fb4a8626ede264c49bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/36aa1fcdd4e4aa7e23408a48efc28dd1c95ac43cce6e8599fd950a42e1165214b1c5901e6ca3cd7cfb28e544640c2face459b44ae17e2c214a647f458c0d753e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/907b0d735e11bf55562306846c7c623e3f69cf8d58950398f18815901b55e4e4e863e72faa29575e538581845d064fe6b252dc283ff0a8faa03923c0245aa025"} 12:18:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:18:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:18:23 INFO - Contents: 12:18:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b806c76820ed708e180e0453514b58bd118f59c2b914291aa34c13c3d7ff92b75533d051fb9a3f3d82018551d47d603cba58f35b01088fb4a8626ede264c49bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/36aa1fcdd4e4aa7e23408a48efc28dd1c95ac43cce6e8599fd950a42e1165214b1c5901e6ca3cd7cfb28e544640c2face459b44ae17e2c214a647f458c0d753e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/907b0d735e11bf55562306846c7c623e3f69cf8d58950398f18815901b55e4e4e863e72faa29575e538581845d064fe6b252dc283ff0a8faa03923c0245aa025"} 12:18:23 INFO - Running post-run listener: copy_logs_to_upload_dir 12:18:23 INFO - Copying logs to upload dir... 12:18:23 INFO - mkdir: /builds/slave/test/build/upload/logs 12:18:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.430660 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-06 12:18:23.609307) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 12:18:23.613973) ========= 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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/Larch/sha512/b806c76820ed708e180e0453514b58bd118f59c2b914291aa34c13c3d7ff92b75533d051fb9a3f3d82018551d47d603cba58f35b01088fb4a8626ede264c49bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/36aa1fcdd4e4aa7e23408a48efc28dd1c95ac43cce6e8599fd950a42e1165214b1c5901e6ca3cd7cfb28e544640c2face459b44ae17e2c214a647f458c0d753e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/907b0d735e11bf55562306846c7c623e3f69cf8d58950398f18815901b55e4e4e863e72faa29575e538581845d064fe6b252dc283ff0a8faa03923c0245aa025"} build_url:https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010874 build_url: 'https://queue.taskcluster.net/v1/task/Tm6_GmqLQaGKpoK6-ck9fw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b806c76820ed708e180e0453514b58bd118f59c2b914291aa34c13c3d7ff92b75533d051fb9a3f3d82018551d47d603cba58f35b01088fb4a8626ede264c49bf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/36aa1fcdd4e4aa7e23408a48efc28dd1c95ac43cce6e8599fd950a42e1165214b1c5901e6ca3cd7cfb28e544640c2face459b44ae17e2c214a647f458c0d753e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/907b0d735e11bf55562306846c7c623e3f69cf8d58950398f18815901b55e4e4e863e72faa29575e538581845d064fe6b252dc283ff0a8faa03923c0245aa025"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 12:18:23.645761) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:18:23.646047) ========= 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.Co8jRS0z3q/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.s7Ic3gD3VN/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.005128 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:18:23.694167) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 12:18:23.694464) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 12:18:23.694779) ========= ========= Total master_lag: 0.18 =========