builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0352 starttime: 1472747811.24 results: success (0) buildid: 20160901063922 builduid: b4052cdda08d410783195fba524c6221 revision: 212b8ad12f3cc173e4f9b03d8e38909fd31277b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:51.235534) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:51.235921) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:51.236206) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:51.634187) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:51.634495) ========= 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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-01 09:36:51-- 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% 56.2M=0s 2016-09-01 09:36:52 (56.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.531540 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:52.190842) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:52.191166) ========= 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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.026391 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:52.239165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 09:36:52.239495) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 212b8ad12f3cc173e4f9b03d8e38909fd31277b9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 212b8ad12f3cc173e4f9b03d8e38909fd31277b9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PCHeZcNNRL/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.1SdGtPsMG8/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-01 09:36:52,327 truncating revision to first 12 chars 2016-09-01 09:36:52,327 Setting DEBUG logging. 2016-09-01 09:36:52,328 attempt 1/10 2016-09-01 09:36:52,328 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/212b8ad12f3c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 09:36:53,107 unpacking tar archive at: fx-team-212b8ad12f3c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.045652 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 09:36:53.307220) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:53.307518) ========= script_repo_revision: 212b8ad12f3cc173e4f9b03d8e38909fd31277b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:53.307933) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:53.308197) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 09:36:53.326788) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 09:36:53.327158) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PCHeZcNNRL/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.1SdGtPsMG8/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:36:53 INFO - MultiFileLogger online at 20160901 09:36:53 in /builds/slave/test 09:36:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:36:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:36:53 INFO - {'append_to_log': False, 09:36:53 INFO - 'base_work_dir': '/builds/slave/test', 09:36:53 INFO - 'blob_upload_branch': 'Fx-Team', 09:36:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:36:53 INFO - 'branch': 'Fx-Team', 09:36:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:36:53 INFO - 'config_files': ('talos/mac_config.py',), 09:36:53 INFO - 'default_actions': ('clobber', 09:36:53 INFO - 'read-buildbot-config', 09:36:53 INFO - 'download-and-extract', 09:36:53 INFO - 'populate-webroot', 09:36:53 INFO - 'create-virtualenv', 09:36:53 INFO - 'install', 09:36:53 INFO - 'run-tests'), 09:36:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:36:53 INFO - 'download_minidump_stackwalk': True, 09:36:53 INFO - 'download_symbols': 'ondemand', 09:36:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:36:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:36:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:36:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:36:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:36:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:36:53 INFO - 'installer_path': 'installer.exe', 09:36:53 INFO - 'log_level': 'info', 09:36:53 INFO - 'log_name': 'talos', 09:36:53 INFO - 'log_to_console': True, 09:36:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:36:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:36:53 INFO - 'opt_config_files': (), 09:36:53 INFO - 'pip_index': False, 09:36:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:53 INFO - 'cmd': ('bash', 09:36:53 INFO - '-c', 09:36:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:53 INFO - 'enabled': True, 09:36:53 INFO - 'halt_on_failure': False, 09:36:53 INFO - 'name': 'check_screen_resolution'},), 09:36:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:36:53 INFO - 'cmd': ('bash', 09:36:53 INFO - '-c', 09:36:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:36:53 INFO - 'enabled': True, 09:36:53 INFO - 'halt_on_failure': False, 09:36:53 INFO - 'name': 'check_screen_resolution'},), 09:36:53 INFO - 'run_cmd_checks_enabled': True, 09:36:53 INFO - 'sps_profile': False, 09:36:53 INFO - 'sps_profile_interval': 0, 09:36:53 INFO - 'suite': 'dromaeojs', 09:36:53 INFO - 'system_bits': '32', 09:36:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:36:53 INFO - 'title': 't-yosemite-r7-0352', 09:36:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:36:53 INFO - 'use_talos_json': True, 09:36:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:36:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:36:53 INFO - 'work_dir': 'build'} 09:36:53 INFO - [mozharness: 2016-09-01 16:36:53.528742Z] Running clobber step. 09:36:53 INFO - Running pre-action listener: _resource_record_pre_action 09:36:53 INFO - Running main action method: clobber 09:36:53 INFO - rmtree: /builds/slave/test/build 09:36:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:36:55 INFO - Running post-action listener: _resource_record_post_action 09:36:55 INFO - [mozharness: 2016-09-01 16:36:55.624109Z] Finished clobber step (success) 09:36:55 INFO - [mozharness: 2016-09-01 16:36:55.624253Z] Running read-buildbot-config step. 09:36:55 INFO - Running pre-action listener: _resource_record_pre_action 09:36:55 INFO - Running main action method: read_buildbot_config 09:36:55 INFO - Using buildbot properties: 09:36:55 INFO - { 09:36:55 INFO - "project": "", 09:36:55 INFO - "product": "firefox", 09:36:55 INFO - "script_repo_revision": "production", 09:36:55 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:36:55 INFO - "repository": "", 09:36:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:36:55 INFO - "buildid": "20160901063922", 09:36:55 INFO - "pgo_build": "False", 09:36:55 INFO - "basedir": "/builds/slave/test", 09:36:55 INFO - "buildnumber": 151, 09:36:55 INFO - "slavename": "t-yosemite-r7-0352", 09:36:55 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:36:55 INFO - "platform": "yosemite_r7", 09:36:55 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:36:55 INFO - "branch": "fx-team", 09:36:55 INFO - "repo_path": "integration/fx-team", 09:36:55 INFO - "revision": "212b8ad12f3cc173e4f9b03d8e38909fd31277b9", 09:36:55 INFO - "stage_platform": "macosx64", 09:36:55 INFO - "builduid": "b4052cdda08d410783195fba524c6221", 09:36:55 INFO - "slavebuilddir": "test" 09:36:55 INFO - } 09:36:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:36:55 INFO - Running post-action listener: _resource_record_post_action 09:36:55 INFO - [mozharness: 2016-09-01 16:36:55.627236Z] Finished read-buildbot-config step (success) 09:36:55 INFO - [mozharness: 2016-09-01 16:36:55.627343Z] Running download-and-extract step. 09:36:55 INFO - Running pre-action listener: _resource_record_pre_action 09:36:55 INFO - Running main action method: download_and_extract 09:36:55 INFO - mkdir: /builds/slave/test/build/tests 09:36:55 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')]} 09:36:55 INFO - trying https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:36:55 INFO - Downloading https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/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 09:36:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/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 09:36:56 INFO - Downloaded 1199 bytes. 09:36:56 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:36:56 INFO - Using the following test package requirements: 09:36:56 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:36:56 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:36:56 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:36:56 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:36:56 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:36:56 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:36:56 INFO - u'jsshell-mac.zip'], 09:36:56 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:36:56 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:36:56 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:36:56 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:36:56 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:36:56 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:36:56 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:36:56 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:36:56 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:36:56 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:36:56 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:36:56 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:36:56 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')]} 09:36:56 INFO - trying https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:36:56 INFO - Downloading https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/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 09:36:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/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 09:36:57 INFO - Downloaded 16399993 bytes. 09:36:57 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:37:04 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 09:37:04 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')]} 09:37:04 INFO - trying https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:37:04 INFO - Downloading https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/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 09:37:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/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 09:37:05 INFO - Downloaded 16399993 bytes. 09:37:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:37:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:12 INFO - trying https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:37:12 INFO - Downloading https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/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 09:37:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/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 09:37:13 INFO - Downloaded 14413998 bytes. 09:37:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:14 INFO - trying https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:37:14 INFO - Downloading https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:37:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/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 09:37:16 INFO - Downloaded 111600342 bytes. 09:37:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:37:16 INFO - mkdir: /builds/slave/test/properties 09:37:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:16 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:16 INFO - Contents: 09:37:16 INFO - build_url:https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:37:17 INFO - Running post-action listener: _resource_record_post_action 09:37:17 INFO - Running post-action listener: set_extra_try_arguments 09:37:17 INFO - [mozharness: 2016-09-01 16:37:17.669429Z] Finished download-and-extract step (success) 09:37:17 INFO - [mozharness: 2016-09-01 16:37:17.669557Z] Running populate-webroot step. 09:37:17 INFO - Running pre-action listener: _resource_record_pre_action 09:37:17 INFO - Running main action method: populate_webroot 09:37:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:17 INFO - '--tppagecycles', 09:37:17 INFO - '7'], 09:37:17 INFO - 'tests': ['tsvgm']}}, 09:37:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:17 INFO - 'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:17 INFO - 'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['damp', 'tps']}, 09:37:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:17 INFO - 'tests': ['damp', 'tps']}, 09:37:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['dromaeo_dom']}, 09:37:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['basic_compositor_video']}, 09:37:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:37:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['a11yr', 09:37:17 INFO - 'ts_paint', 09:37:17 INFO - 'tpaint', 09:37:17 INFO - 'sessionrestore', 09:37:17 INFO - 'sessionrestore_no_auto_restore', 09:37:17 INFO - 'tabpaint']}, 09:37:17 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:17 INFO - 'ts_paint', 09:37:17 INFO - 'tpaint', 09:37:17 INFO - 'sessionrestore', 09:37:17 INFO - 'sessionrestore_no_auto_restore', 09:37:17 INFO - 'tabpaint']}, 09:37:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:37:17 INFO - 'ts_paint', 09:37:17 INFO - 'tpaint', 09:37:17 INFO - 'sessionrestore', 09:37:17 INFO - 'sessionrestore_no_auto_restore', 09:37:17 INFO - 'tabpaint']}, 09:37:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:37:17 INFO - 'ts_paint', 09:37:17 INFO - 'tpaint', 09:37:17 INFO - 'sessionrestore', 09:37:17 INFO - 'sessionrestore_no_auto_restore', 09:37:17 INFO - 'tabpaint']}, 09:37:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['a11yr', 09:37:17 INFO - 'ts_paint', 09:37:17 INFO - 'tpaint', 09:37:17 INFO - 'sessionrestore', 09:37:17 INFO - 'sessionrestore_no_auto_restore', 09:37:17 INFO - 'tabpaint']}, 09:37:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['a11yr', 09:37:17 INFO - 'ts_paint', 09:37:17 INFO - 'tpaint', 09:37:17 INFO - 'sessionrestore', 09:37:17 INFO - 'sessionrestore_no_auto_restore', 09:37:17 INFO - 'tabpaint']}, 09:37:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['tsvgx', 09:37:17 INFO - 'tsvgr_opacity', 09:37:17 INFO - 'tart', 09:37:17 INFO - 'tscrollx', 09:37:17 INFO - 'cart']}, 09:37:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:17 INFO - 'tsvgr_opacity', 09:37:17 INFO - 'tart', 09:37:17 INFO - 'tscrollx', 09:37:17 INFO - 'cart']}, 09:37:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:17 INFO - 'talos_options': ['--disable-e10s'], 09:37:17 INFO - 'tests': ['tp5o']}, 09:37:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:17 INFO - 'tests': ['tp5o']}, 09:37:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:17 INFO - 'talos_options': ['--disable-e10s', 09:37:17 INFO - '--xperf_path', 09:37:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:17 INFO - 'tests': ['tp5n']}, 09:37:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:17 INFO - 'talos_options': ['--xperf_path', 09:37:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:17 INFO - 'tests': ['tp5n']}}, 09:37:17 INFO - 'talos.zip': {'path': '', 09:37:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:17 INFO - Running post-action listener: _resource_record_post_action 09:37:17 INFO - [mozharness: 2016-09-01 16:37:17.688734Z] Finished populate-webroot step (success) 09:37:17 INFO - [mozharness: 2016-09-01 16:37:17.688849Z] Running create-virtualenv step. 09:37:17 INFO - Running pre-action listener: _resource_record_pre_action 09:37:17 INFO - Running main action method: create_virtualenv 09:37:17 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:17 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 09:37:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:17 INFO - Using real prefix '/tools/python27' 09:37:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:18 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:21 INFO - Installing pip.................done. 09:37:21 INFO - Return code: 0 09:37:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:21 INFO - 'HOME': '/Users/cltbld', 09:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:21 INFO - 'LOGNAME': 'cltbld', 09:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:21 INFO - 'MOZ_NO_REMOTE': '1', 09:37:21 INFO - 'NO_EM_RESTART': '1', 09:37:21 INFO - 'PAGER': '/bin/cat', 09:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:21 INFO - 'PWD': '/builds/slave/test', 09:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:21 INFO - 'SHELL': '/bin/bash', 09:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:21 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:21 INFO - 'USER': 'cltbld', 09:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:21 INFO - 'XPC_FLAGS': '0x0', 09:37:21 INFO - 'XPC_SERVICE_NAME': '0', 09:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:21 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 09:37:21 INFO - Cleaning up... 09:37:21 INFO - Return code: 0 09:37:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:21 INFO - 'HOME': '/Users/cltbld', 09:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:21 INFO - 'LOGNAME': 'cltbld', 09:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:21 INFO - 'MOZ_NO_REMOTE': '1', 09:37:21 INFO - 'NO_EM_RESTART': '1', 09:37:21 INFO - 'PAGER': '/bin/cat', 09:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:21 INFO - 'PWD': '/builds/slave/test', 09:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:21 INFO - 'SHELL': '/bin/bash', 09:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:21 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:21 INFO - 'USER': 'cltbld', 09:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:21 INFO - 'XPC_FLAGS': '0x0', 09:37:21 INFO - 'XPC_SERVICE_NAME': '0', 09:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:21 INFO - Downloading/unpacking psutil>=3.1.1 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:24 INFO - Installing collected packages: psutil 09:37:24 INFO - Running setup.py install for psutil 09:37:25 INFO - building 'psutil._psutil_osx' extension 09:37:25 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 09:37:25 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 09:37:25 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 09:37:25 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 09:37:25 INFO - building 'psutil._psutil_posix' extension 09:37:25 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 09:37:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:37:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:37:25 INFO - ^ 09:37:25 INFO - 1 warning generated. 09:37:25 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 09:37:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:26 INFO - Successfully installed psutil 09:37:26 INFO - Cleaning up... 09:37:26 INFO - Return code: 0 09:37:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:37:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:26 INFO - 'HOME': '/Users/cltbld', 09:37:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:26 INFO - 'LOGNAME': 'cltbld', 09:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:26 INFO - 'MOZ_NO_REMOTE': '1', 09:37:26 INFO - 'NO_EM_RESTART': '1', 09:37:26 INFO - 'PAGER': '/bin/cat', 09:37:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:26 INFO - 'PWD': '/builds/slave/test', 09:37:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:26 INFO - 'SHELL': '/bin/bash', 09:37:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:26 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:26 INFO - 'USER': 'cltbld', 09:37:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:26 INFO - 'XPC_FLAGS': '0x0', 09:37:26 INFO - 'XPC_SERVICE_NAME': '0', 09:37:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:28 INFO - Installing collected packages: mozsystemmonitor 09:37:28 INFO - Running setup.py install for mozsystemmonitor 09:37:29 INFO - Successfully installed mozsystemmonitor 09:37:29 INFO - Cleaning up... 09:37:29 INFO - Return code: 0 09:37:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:29 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 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:29 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 09:37:29 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 09:37:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:29 INFO - 'HOME': '/Users/cltbld', 09:37:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:29 INFO - 'LOGNAME': 'cltbld', 09:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:29 INFO - 'MOZ_NO_REMOTE': '1', 09:37:29 INFO - 'NO_EM_RESTART': '1', 09:37:29 INFO - 'PAGER': '/bin/cat', 09:37:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:29 INFO - 'PWD': '/builds/slave/test', 09:37:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:29 INFO - 'SHELL': '/bin/bash', 09:37:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:29 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:29 INFO - 'USER': 'cltbld', 09:37:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:29 INFO - 'XPC_FLAGS': '0x0', 09:37:29 INFO - 'XPC_SERVICE_NAME': '0', 09:37:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:29 INFO - Downloading/unpacking jsonschema==2.5.1 09:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:32 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:32 INFO - warning: no files found matching '*.txt' 09:37:32 INFO - no previously-included directories found matching 'build' 09:37:32 INFO - no previously-included directories found matching 'dist' 09:37:32 INFO - no previously-included directories found matching '.git*' 09:37:32 INFO - Installing collected packages: jsonschema, functools32 09:37:32 INFO - Running setup.py install for functools32 09:37:32 INFO - warning: no files found matching '*.txt' 09:37:32 INFO - no previously-included directories found matching 'build' 09:37:32 INFO - no previously-included directories found matching 'dist' 09:37:32 INFO - no previously-included directories found matching '.git*' 09:37:32 INFO - Successfully installed jsonschema functools32 09:37:32 INFO - Cleaning up... 09:37:32 INFO - Return code: 0 09:37:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:32 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')]} 09:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:32 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')]} 09:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:32 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 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:32 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 09:37:32 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 09:37:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:32 INFO - 'HOME': '/Users/cltbld', 09:37:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:32 INFO - 'LOGNAME': 'cltbld', 09:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:32 INFO - 'MOZ_NO_REMOTE': '1', 09:37:32 INFO - 'NO_EM_RESTART': '1', 09:37:32 INFO - 'PAGER': '/bin/cat', 09:37:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:32 INFO - 'PWD': '/builds/slave/test', 09:37:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:32 INFO - 'SHELL': '/bin/bash', 09:37:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:32 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:32 INFO - 'USER': 'cltbld', 09:37:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:32 INFO - 'XPC_FLAGS': '0x0', 09:37:32 INFO - 'XPC_SERVICE_NAME': '0', 09:37:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:32 INFO - Cleaning up... 09:37:32 INFO - Return code: 0 09:37:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:32 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')]} 09:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:32 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')]} 09:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:32 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 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:32 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 09:37:32 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 09:37:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:32 INFO - 'HOME': '/Users/cltbld', 09:37:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:32 INFO - 'LOGNAME': 'cltbld', 09:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:32 INFO - 'MOZ_NO_REMOTE': '1', 09:37:32 INFO - 'NO_EM_RESTART': '1', 09:37:32 INFO - 'PAGER': '/bin/cat', 09:37:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:32 INFO - 'PWD': '/builds/slave/test', 09:37:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:32 INFO - 'SHELL': '/bin/bash', 09:37:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:32 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:32 INFO - 'USER': 'cltbld', 09:37:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:32 INFO - 'XPC_FLAGS': '0x0', 09:37:32 INFO - 'XPC_SERVICE_NAME': '0', 09:37:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:33 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:35 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37: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 09:37:36 INFO - Downloading docopt-0.6.1.tar.gz 09:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:36 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:36 INFO - Running setup.py install for blobuploader 09:37:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:37 INFO - Running setup.py install for requests 09:37:37 INFO - Running setup.py install for docopt 09:37:37 INFO - Successfully installed blobuploader requests docopt 09:37:37 INFO - Cleaning up... 09:37:37 INFO - Return code: 0 09:37:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:37 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')]} 09:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:37 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')]} 09:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:37 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 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:37 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 09:37:37 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 09:37:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:37 INFO - 'HOME': '/Users/cltbld', 09:37:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:37 INFO - 'LOGNAME': 'cltbld', 09:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:37 INFO - 'MOZ_NO_REMOTE': '1', 09:37:37 INFO - 'NO_EM_RESTART': '1', 09:37:37 INFO - 'PAGER': '/bin/cat', 09:37:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:37 INFO - 'PWD': '/builds/slave/test', 09:37:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:37 INFO - 'SHELL': '/bin/bash', 09:37:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:37 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:37 INFO - 'USER': 'cltbld', 09:37:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:37 INFO - 'XPC_FLAGS': '0x0', 09:37:37 INFO - 'XPC_SERVICE_NAME': '0', 09:37:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-GFsMXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-2eBtOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-c0SwYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-d_UpPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-9IbM4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-QCcrLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-hTBvVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-YLVRdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-mA6PPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:39 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-5scv2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:39 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-YxghNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:39 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-VxV961-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:39 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-9X9HUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:39 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-IyocP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:39 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-EXmNQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:39 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-FW184i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:39 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-vCrF6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:39 INFO - Running setup.py install for manifestparser 09:37:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:39 INFO - Running setup.py install for mozcrash 09:37:40 INFO - Running setup.py install for mozdebug 09:37:40 INFO - Running setup.py install for mozdevice 09:37:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:37:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:40 INFO - Running setup.py install for mozfile 09:37:40 INFO - Running setup.py install for mozhttpd 09:37:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:40 INFO - Running setup.py install for mozinfo 09:37:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:40 INFO - Running setup.py install for mozInstall 09:37:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Running setup.py install for mozleak 09:37:41 INFO - Running setup.py install for mozlog 09:37:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Running setup.py install for moznetwork 09:37:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Running setup.py install for mozprocess 09:37:41 INFO - Running setup.py install for mozprofile 09:37:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Running setup.py install for mozrunner 09:37:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:37:41 INFO - Running setup.py install for mozscreenshot 09:37:42 INFO - Running setup.py install for moztest 09:37:42 INFO - Running setup.py install for mozversion 09:37:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:37:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:37:42 INFO - Cleaning up... 09:37:42 INFO - Return code: 0 09:37:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:42 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 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:42 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 09:37:42 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 09:37:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:42 INFO - 'HOME': '/Users/cltbld', 09:37:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:42 INFO - 'LOGNAME': 'cltbld', 09:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:42 INFO - 'MOZ_NO_REMOTE': '1', 09:37:42 INFO - 'NO_EM_RESTART': '1', 09:37:42 INFO - 'PAGER': '/bin/cat', 09:37:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:42 INFO - 'PWD': '/builds/slave/test', 09:37:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:42 INFO - 'SHELL': '/bin/bash', 09:37:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:42 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:42 INFO - 'USER': 'cltbld', 09:37:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:42 INFO - 'XPC_FLAGS': '0x0', 09:37:42 INFO - 'XPC_SERVICE_NAME': '0', 09:37:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:42 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-gTa5gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:42 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)) 09:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:42 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-6PML7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:42 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)) 09:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:42 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-HHRCgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Rx9Lo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-V3YaSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-HeYO6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-OoAOat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-VA9onV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-KrWFEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-RPhCVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-z29Jha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-wtEeyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:43 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)) 09:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:43 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-SqegwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:44 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)) 09:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-1EqNSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:44 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)) 09:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-b521xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:44 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)) 09:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-47QkSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:44 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)) 09:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:44 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-zWzOaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:44 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)) 09:37:44 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)) 09:37:44 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)) 09:37:44 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)) 09:37:44 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)) 09:37:44 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)) 09:37:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:37:44 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 09:37:44 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 09:37:44 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 09:37:44 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 09:37:44 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 09:37:44 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 09:37:46 INFO - Downloading blessings-1.6.tar.gz 09:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:37:47 INFO - Installing collected packages: blessings 09:37:47 INFO - Running setup.py install for blessings 09:37:47 INFO - Successfully installed blessings 09:37:47 INFO - Cleaning up... 09:37:47 INFO - Return code: 0 09:37:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:37:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:47 INFO - Reading from file tmpfile_stdout 09:37:47 INFO - Current package versions: 09:37:47 INFO - blessings == 1.6 09:37:47 INFO - blobuploader == 1.2.4 09:37:47 INFO - docopt == 0.6.1 09:37:47 INFO - functools32 == 3.2.3-2 09:37:47 INFO - jsonschema == 2.5.1 09:37:47 INFO - manifestparser == 1.1 09:37:47 INFO - mozInstall == 1.12 09:37:47 INFO - mozcrash == 0.17 09:37:47 INFO - mozdebug == 0.1 09:37:47 INFO - mozdevice == 0.48 09:37:47 INFO - mozfile == 1.2 09:37:47 INFO - mozhttpd == 0.7 09:37:47 INFO - mozinfo == 0.9 09:37:47 INFO - mozleak == 0.1 09:37:47 INFO - mozlog == 3.3 09:37:47 INFO - moznetwork == 0.27 09:37:47 INFO - mozprocess == 0.23 09:37:47 INFO - mozprofile == 0.28 09:37:47 INFO - mozrunner == 6.12 09:37:47 INFO - mozscreenshot == 0.1 09:37:47 INFO - mozsystemmonitor == 0.3 09:37:47 INFO - moztest == 0.7 09:37:47 INFO - mozversion == 1.4 09:37:47 INFO - psutil == 3.1.1 09:37:47 INFO - requests == 1.2.3 09:37:47 INFO - wsgiref == 0.1.2 09:37:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:47 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 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:47 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 09:37:47 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 09:37:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:47 INFO - 'HOME': '/Users/cltbld', 09:37:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:47 INFO - 'LOGNAME': 'cltbld', 09:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:47 INFO - 'MOZ_NO_REMOTE': '1', 09:37:47 INFO - 'NO_EM_RESTART': '1', 09:37:47 INFO - 'PAGER': '/bin/cat', 09:37:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:47 INFO - 'PWD': '/builds/slave/test', 09:37:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:47 INFO - 'SHELL': '/bin/bash', 09:37:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:47 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:47 INFO - 'USER': 'cltbld', 09:37:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:47 INFO - 'XPC_FLAGS': '0x0', 09:37:47 INFO - 'XPC_SERVICE_NAME': '0', 09:37:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:47 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)) 09:37:47 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)) 09:37:47 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)) 09:37:47 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)) 09:37:47 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)) 09:37:47 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)) 09:37:47 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)) 09:37:47 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)) 09:37:47 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)) 09:37:47 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)) 09:37:47 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)) 09:37:47 INFO - Cleaning up... 09:37:47 INFO - Return code: 0 09:37:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:47 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 0x1028a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102937618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb5e2600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, '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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 09:37:47 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 09:37:47 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 09:37:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:37:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:47 INFO - 'HOME': '/Users/cltbld', 09:37:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:47 INFO - 'LOGNAME': 'cltbld', 09:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:47 INFO - 'MOZ_NO_REMOTE': '1', 09:37:47 INFO - 'NO_EM_RESTART': '1', 09:37:47 INFO - 'PAGER': '/bin/cat', 09:37:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:47 INFO - 'PWD': '/builds/slave/test', 09:37:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:47 INFO - 'SHELL': '/bin/bash', 09:37:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:37:47 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:37:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:47 INFO - 'USER': 'cltbld', 09:37:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:47 INFO - 'XPC_FLAGS': '0x0', 09:37:47 INFO - 'XPC_SERVICE_NAME': '0', 09:37:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:37:48 INFO - Cleaning up... 09:37:48 INFO - Return code: 0 09:37:48 INFO - Running post-action listener: _resource_record_post_action 09:37:48 INFO - Running post-action listener: _start_resource_monitoring 09:37:48 INFO - Starting resource monitoring. 09:37:48 INFO - [mozharness: 2016-09-01 16:37:48.172496Z] Finished create-virtualenv step (success) 09:37:48 INFO - [mozharness: 2016-09-01 16:37:48.173542Z] Running install step. 09:37:48 INFO - Running pre-action listener: _resource_record_pre_action 09:37:48 INFO - Running main action method: install 09:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:37:48 INFO - Reading from file tmpfile_stdout 09:37:48 INFO - Detecting whether we're running mozinstall >=1.0... 09:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:37:48 INFO - Reading from file tmpfile_stdout 09:37:48 INFO - Output received: 09:37:48 INFO - Usage: mozinstall [options] installer 09:37:48 INFO - Options: 09:37:48 INFO - -h, --help show this help message and exit 09:37:48 INFO - -d DEST, --destination=DEST 09:37:48 INFO - Directory to install application into. [default: 09:37:48 INFO - "/builds/slave/test"] 09:37:48 INFO - --app=APP Application being installed. [default: firefox] 09:37:48 INFO - mkdir: /builds/slave/test/build/application 09:37:48 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'] 09:37:48 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 09:38:22 INFO - Reading from file tmpfile_stdout 09:38:22 INFO - Output received: 09:38:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:38:22 INFO - Running post-action listener: _resource_record_post_action 09:38:22 INFO - [mozharness: 2016-09-01 16:38:22.626500Z] Finished install step (success) 09:38:22 INFO - [mozharness: 2016-09-01 16:38:22.626685Z] Running run-tests step. 09:38:22 INFO - Running pre-action listener: _resource_record_pre_action 09:38:22 INFO - Running main action method: run_tests 09:38:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:22 INFO - 2016-09-01 09:38:22.641 screenresolution[1642:8376] starting screenresolution argv=screenresolution get 09:38:22 INFO - 2016-09-01 09:38:22.643 screenresolution[1642:8376] Display 0: 1600x1200x32@60 09:38:22 INFO - 2016-09-01 09:38:22.650 screenresolution[1643:8378] starting screenresolution argv=screenresolution list 09:38:22 INFO - Available Modes on Display 0 09:38:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:22 INFO - Intel Iris: 09:38:22 INFO - Chipset Model: Intel Iris 09:38:22 INFO - Type: GPU 09:38:22 INFO - Bus: Built-In 09:38:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:22 INFO - Vendor: Intel (0x8086) 09:38:22 INFO - Device ID: 0x0a2e 09:38:22 INFO - Revision ID: 0x0009 09:38:22 INFO - Displays: 09:38:22 INFO - Display: 09:38:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:22 INFO - Display Serial Number: 1600x1200 60 09:38:22 INFO - Main Display: Yes 09:38:22 INFO - Mirror: Off 09:38:22 INFO - Online: Yes 09:38:22 INFO - Rotation: Supported 09:38:22 INFO - Return code: 0 09:38:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:22 INFO - Python 2.7.3 09:38:22 INFO - Return code: 0 09:38:22 INFO - grabbing minidump binary from tooltool 09:38:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:22 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 0x102806d30>, '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 0x10256bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027feb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:22 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 09:38:22 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 09:38:22 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 09:38:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:23 INFO - Return code: 0 09:38:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:38:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:38:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 09:38:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0352 --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 09:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PCHeZcNNRL/Render', 09:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:23 INFO - 'HOME': '/Users/cltbld', 09:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:23 INFO - 'LOGNAME': 'cltbld', 09:38:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:23 INFO - 'MOZ_NO_REMOTE': '1', 09:38:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:23 INFO - 'NO_EM_RESTART': '1', 09:38:23 INFO - 'PAGER': '/bin/cat', 09:38:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:23 INFO - 'PWD': '/builds/slave/test', 09:38:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:23 INFO - 'SHELL': '/bin/bash', 09:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1SdGtPsMG8/Listeners', 09:38:23 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 09:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:23 INFO - 'USER': 'cltbld', 09:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:23 INFO - 'XPC_FLAGS': '0x0', 09:38:23 INFO - 'XPC_SERVICE_NAME': '0', 09:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 09:38:23 INFO - mozversion application_buildid: 20160901063922 09:38:23 INFO - mozversion application_changeset: 212b8ad12f3cc173e4f9b03d8e38909fd31277b9 09:38:23 INFO - mozversion application_display_name: Nightly 09:38:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:23 INFO - mozversion application_name: Firefox 09:38:23 INFO - mozversion application_remotingname: firefox 09:38:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:38:23 INFO - mozversion application_vendor: Mozilla 09:38:23 INFO - mozversion application_version: 51.0a1 09:38:23 INFO - mozversion platform_buildid: 20160901063922 09:38:23 INFO - mozversion platform_changeset: 212b8ad12f3cc173e4f9b03d8e38909fd31277b9 09:38:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:38:23 INFO - mozversion platform_version: 51.0a1 09:38:23 INFO - using testdate: 1472747903 09:38:23 INFO - actual date: 1472747903 09:38:23 INFO - starting webserver on 'localhost:49262' 09:38:23 INFO - SUITE-START | Running 2 tests 09:38:23 INFO - TEST-START | dromaeo_css 09:38:23 INFO - operating with platform_type : mac_ 09:38:23 INFO - Initialising browser for dromaeo_css test... 09:38:23 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpViLHXp/profile http://localhost:49262/getInfo.html) 09:38:25 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:38:25 INFO - PROCESS | 1653 | colorDepth:24 09:38:25 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:38:25 INFO - PROCESS | 1653 | __metrics 09:38:25 INFO - PROCESS | 1653 | 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" 09:38:25 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:38:26 INFO - TEST-INFO | 1653: exit 0 09:38:26 INFO - Browser initialized. 09:38:26 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:26 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpViLHXp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:38 INFO - PROCESS | 1656 | RSS: Main: 233750528 09:38:38 INFO - PROCESS | 1656 | 09:39:30 INFO - PROCESS | 1656 | 1472747970475 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] 09:41:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49262/tests/dromaeo/cssquery-ext.html) 09:41:43 INFO - PROCESS | 1656 | RSS: Main: 378966016 09:41:43 INFO - PROCESS | 1656 | 09:44:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-ext.html (next: http://localhost:49262/tests/dromaeo/cssquery-jquery.html) 09:44:47 INFO - PROCESS | 1656 | RSS: Main: 371322880 09:44:47 INFO - PROCESS | 1656 | 09:47:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49262/tests/dromaeo/cssquery-mootools.html) 09:47:50 INFO - PROCESS | 1656 | RSS: Main: 339820544 09:47:50 INFO - PROCESS | 1656 | 09:50:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49262/tests/dromaeo/cssquery-prototype.html) 09:50:53 INFO - PROCESS | 1656 | RSS: Main: 350912512 09:50:53 INFO - PROCESS | 1656 | 09:53:56 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49262/tests/dromaeo/cssquery-yui.html) 09:53:57 INFO - PROCESS | 1656 | RSS: Main: 334016512 09:53:57 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-yui.html (next: http://localhost:49262/tests/dromaeo/cssquery-dojo.html) 09:57:01 INFO - PROCESS | 1656 | RSS: Main: 389500928 09:57:01 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | __start_tp_report 09:57:01 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 09:57:01 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 09:57:01 INFO - PROCESS | 1656 | |i|pagename|runs| 09:57:01 INFO - PROCESS | 1656 | |0;dojo.html;3213;3353.211995181133;3390.627031026585;3421.914452138698;3441.1397150712323;5662.858428539289;5689.061304884694;5738.16796564499;5797.855053623662;5804.071348584227;5750.6262092963925;5764.308283006045;5859.824205273838;5861.267341582302;5907.763689452417;5152.201408781626;5153.2785410042625;5229.555487783524;5239.187925871496;5326.973365133169;5326.201069839516;5330.946690533084;5351.785928562853;5355.357357117132;5379.542738867201;5204.5576126028955;5224.947750522484;5242.659227150261;5260.079486089938;5285.101532739443;5271.998320319165;5275.498827611371;5284.233786101032;5287.735613219324;5293.444188360205;5170.405403378622;5191.818286359997;5240.895182096375;5262.2632831403635;5269.473052694738;5281.9207711884355;5506.834794956158;5719.828405147852;5832.270966129234;5837.153612726131;6244.594101383395;6277.121203031579;6277.623342599459;6312.558120931537;6340.746370145189;6264.686765661744;6300.054991751228;6306.1171435998995;6324;6343.7145328459865;5719.170720245625;5743.454371834628;5784.739686714108;5812.099124635688;5829.09649004405;6280.748770049238;6284.088807123033;6298.1812364392335;6298.905516417258;6300.3699630037545;6210.31686514483;6216.720247588869;6225.501959843202;6229.688515574203;6230.221222347207;6123.069106197319;6219.968900155471;6280.2777680566405;6284.12022316876;6327.96836015817;6281.277653069864;6295.181626388632;6298.055291706189;6299.992001279773;6313.273973493016;6170.293778773391;6314.905276420857;6332.556721029576;6336.3030066693545;6351.650659213788;4121.175764847043;4903.803847846046;4907.435644900811;4959.404871415453;4966.875828104254;4484.013517026249;4511.751853648081;4513.909721805546;4520.88697782558;4522.389477420506;5799.420057994167;5808.186853840466;5811.767529298865;5824.563157763185;5828.067509198592;6255.46828519581;6257.499400047986;6313.40022697843;6332.050192471166;6334.461570766536;5854.268216472941;5862.882742345129;5864.67744274069;5873.089671100986;5880.470757631833;5453.454654534515;5455.318085239345;5458.1266997280245;5472.124460086367;5472.34331880169;6201.255849298113;6239;6259.624422534663;6262.937370626236;6350.587211831216;6335.239771227298;6341.4292713656;6346.7461301549265;6349.365063493614;6363.1091647168505;6320.936790632035;6321.683915804283;6328.177336946352;6333.2716737575765;6342.873142537216;5057.721825299644;5751.626144300607;5986.431289027535;6127.816165515042;6148.50811935053;6170.50635949132;6341.239051313872;6363.331850155852;6364.77723279683;6373.808785736435;6049.78825741097;6058.212432383762;6079.452849243589;6082.574219804748;6085.148079268817;5899.144624029577;5985.221921150222;6045.546584006129;6052.03167493199;6083.178770865877;5892.351841297368;5931.169636250842;5947.345791962967;5951.791687290924;5992;6054.3642917493025;6075.817725468243;6095.969520152371;6096.298925623432;6120.296165940974;5758.798442054508;5857.355690873916;5885.14665373527;5886.117082437669;5949.553783466344 09:57:01 INFO - PROCESS | 1656 | |1;ext.html;480.4714813704925;496.9279454479101;522.932018837551;532.5180711456132;539.897419490297;25912;36504.26991460169;42762.78618606907;43447.34828977568;43650.472233471875;42991;43353.78323108388;43358.78320608393;43420.13159736803;43432;8826.735197944072;9696.515174241278;9888.01119888009;9895.059969302905;9899.950500247489;11968.640940771791;12247.69380765479;12264.080193985443;12410.627681169579;12411.689707757288;11984.520619175222;12039.939800300986;12060.517579296818;12067.336296503689;12093.818592721116;12258.816117758197;12315.5073797048;12317.260964342167;12331.656985172789;12359.13486055972;12082.33547154906;12103.878961210363;12228.266304021787;12229.327386993711;12231.449584768618;33323.83338083318;40719.59280407188;40850.591494084976;40922.79538602288;40935.771926842244;2845.502037143512;3279.8032118072992;3349.4975753636904;3379.9155021124425;3474.722022238215;30065.549016764766;30553.694463055308;31592.210194745086;31624;31716.04851854425;3278.2623909620384;3303.8182899940493;3360.899173024813;3409.9659003410143;3413.8634454621783;493328;497090;499172;499313;500877.9912200904;33090.00729978104;34180;61745.07382389268;62404.375956240765;62430.75138497295;29575;34474;34578.48132278018;34725.30549389048;34769.30461390758;14289.356978935974;14431.567052988426;14434.061785984088;14440.783388249185;14451.78322325166;11211.943940280247;11412.657620271406;12272.38638068093;12289.016878649687;12387.566435174725;7739.535627862347;7790.6728443802085;7809.570473623891;7868.645910934022;7914.16901225363;3225.7258133058954;3241.8703251870133;3245.3184831185245;3263.853126609308;3287.112479630486;918.4213945214606;966.5602151021268;1021.9473941839917;1024.0476356987924;1066.269605320358;27309.952813138978;29936.25159371033;30149.865121390485;30371.392572148874;30407.23981900426;1005.3615953869346;1032.8244198486275;1062.2139616683598;1086.440483151178;1126.9943650281696;15187;15534.300956456986;15612;15752.976056556286;15866.968647037906;667530;669450;671373.2862671311;674588;675698;57501;58114;58153.418465815645;58214;58309;1194.2416565480808;1237.4988129807623;1238.0095923261536;1335.5592654424017;1338.1435881036145;20506.692399614316;30742.538861917536;31314.373712525165;32607.673923260463;33160.67357305681;54096;54641.453585463634;54738.726306369805;54827;54860;575087;580439;580669;580752.096239533;582093;26320.73679263183;26561.46877062409;26925.3268668277;27131.321716956445;28049.859750701933;46768;46788.7660561695;47924;47952.7602361986;48204;38899;39124.80437597794;39601.80199098986;40718.185636286515;40811.38782918199;34041;38844.028899276615;39051;39899.80050099847;40540.594594053684;37093;37638.623613764605;38492;38705;38784;32166.35667286688;34425.48361774525;34595.71541076415;34714.47928281027;34886.82556587201;25480.745192547838;25600.615990760525;25671.358216044748;26049.348766280993;26252.21243362702 09:57:01 INFO - PROCESS | 1656 | |2;jquery.html;353.19295409988183;369.9889003329901;371.5058971567815;380.2129591745087;400.781574042147;15247.771283430744;16163.595910102253;19971.800281997177;20408.8979555102;20544.486387840294;20592.485187870312;21300.4674883128;21315.57368852626;21323;22169.2240771573;6786.7963961080995;6816.147981502312;7017.473689473284;7103.89344159838;7163.749268775594;4629.259318509039;6958.443324534025;7050.7532236371735;7057.964710176442;7059.647017649123;4710.26990816424;8034.276915077642;8047.798805029892;8059.395545334094;8130.349572034253;7765.844683106363;8028.759137225892;8040.8391832163325;8128.4310098293145;8216.46592971461;7548;7810.882836757453;7887.566183859885;7909.802254943615;7922.960385198095;24168.154114605903;29270.268243293875;29443.411131777244;29526.85236573825;29659.96190133335;2309.3880121767743;2395.2215529952837;2496.213692686798;2742.698303186648;3224.67753224678;16535.83464165355;16857.49427517164;16860.494185174466;17656.823431765646;17692.557686057822;1840.9079546022645;1965.6363572738946;1991.9525118521158;2056.8354531637433;2069.3895300886275;129183.35408322899;144721.99999999895;147362;150591;150644;19811.900940495205;21224.469388265417;21248.25631102904;21287.574248514942;21453;31409.371812563622;32984.8455304068;35028;35094.82452587721;35229;9449.866016078115;9586.856197157049;10585.788284234271;10686;10784.838227426595;8663.783405414915;8671.393002489891;8679.132086791396;8850.424722393023;8860.3797734158;7975.122736498981;9376.765580860532;9450.43297402158;9565.856512152322;9646.951765241129;2927.780416468773;3623.387592521303;3682.3371793077504;3689.3543629864666;3798.8290526926353;847.5804476783908;867.7483529776421;934.4580143516744;955.9283053770995;973.8490533967247;19490.317838875853;20313.78117312966;20525.38423847287;20655.79344206569;20905.268315608882;886.3795343259744;912.6486302773347;939.2251392601131;940.703678341334;996.566493575297;8069.354451643854;8960.507172105597;10736.087432568163;10782.67651970442;10800.567977280822;232759;232797;233019.6698033032;233216;234444;100834;127781;130413;130425.69574304325;132612.01081983777;926.8702381666573;943.2689665509148;964.7250533597891;966.8839739231337;989.1789814453986;14783.408663653334;18095.45713628593;18221;18239.0880455974;18480.983545904568;37945.62054379421;39308.80345598254;53940;53980;55982;73095.63452182704;73600.46318479408;73825.26174738184;76139;76518.61740691261;7187.604681742554;7308.5614863108385;7530.322270995636;8000.719974801087;8231.547264900488;9334.71995840126;12841.935790321362;13470.259135747629;13472.259025753678;13714.451421943011;11313.490892909675;11384.032357249727;11727.824082638597;11737.413129343327;11842.644720658394;9707.563159657691;9755.317127800985;11149.777004459704;11232.887671123512;11684.70788230301;11357.4889129991;11451.255668381862;11834.704132396415;11867.58463453775;11886.524539018343;8077.7576672699915;8205.343572514304;8326.83346333082;8412.831743365221;8560.058393576826;10520.631777887736;11260.774784504429;11655.475503602527;11662.241954272946;11731.35477548743 09:57:01 INFO - PROCESS | 1656 | |3;mootools.html;1524.6874390749897;1533.1950725868921;1759.3842155245666;1910.2550005497867;1911.2259534888376;8528.786780330494;9053.50205738684;9060.229880460161;9076.954615226923;9079.954600226989;7845.80385490363;8093.352531797457;8507.276881465068;8767.517786521754;8845.955770221157;2079.313826437272;2365.38499990003;2402.423418379594;2403.3751224681623;2409.4458274596855;2366.5503554324623;2384.3919800450863;2406.254061241012;2411.0717373811035;2420.3707036170545;2376.263358358914;2394.473215892501;2404.086447150089;2409.795167410772;2413.613821788514;2371.1582388252095;2383.833131680783;2390.9880450597634;2392.557376885282;2400.9279721608204;2369.7985671217966;2387.092904696204;2392.4497365605835;2392.892319845611;2394.461246219604;8707.34694897879;8787.428817126865;8850.911490885073;8869.605206594248;8880.422772519765;5169.870753231199;5314.5216930476445;5317.574594032468;5323.307969963918;5328.760205790749;3610.2779444111284;3614.36748569002;3666.4683620874825;3675.320065787838;3705.4441833724886;1006.9093781559621;1088.1784252889097;1088.2491081153978;1092.4428541443842;1111.9833202501968;11643.301401915913;11735.471903764352;11775.941120294343;11797.528098876035;11815.291082535075;4499.7975091120325;4589.5639914208095;4766.904661906742;4770.833020844254;4782.210935195723;9865.506724663737;9867.555959981819;9879.308448408687;9884.901150988542;9920.355176913477;5324.707141107277;5327.147656374961;5331.840044798662;5332.440093790174;5335.439778823245;4240.1440196996655;4390.495093064338;4547.795349209294;4872.19608764557;4891.6575839691595;3572.3212589607892;3701.9089060634133;3805.9809700951873;3973.92052158961;3988.202359528048;1277.182603133995;1294.9028822838136;1301.355828864718;1316.4339334086435;1316.5194703932996;827.6813426830713;860.979739009276;890.6570970176529;894.7494701483595;927.9350445468888;5047.242913562922;5592.133219351007;5694.971525142348;5945.40545945402;5968.522518198533;839.878761852921;902.8961669408102;942.2038377570941;942.4580866002076;948.62055177928;3800.524934383202;4016.136530645925;4096.836126554964;4099.446574712435;4102.671786257092;10525.36847789135;10639.627613033506;10768.030877221088;10814.513346899408;10957.671269861918;10683.732906677395;10903.618373356894;10907;10912.836307455394;10921.563137474412;7786;8139.348852091818;8207;8241.011078670717;8322;5938.910916336172;5999.5800293979;6088.269407671108;6091.329953705177;6132.325444201224;9899.703008909744;10106.545205465625;10160.695179144637;10166.491675416348;10178.440185789852;9730.318877678777;10387.532561034923;10387.636432724817;10388.532516036948;10404.167666586503;7000.684969176297;7220.458465614995;7612.352949999312;7745.148033716174;7747.18654541265;8231.753047408587;8354.958225208835;8504.914950850412;8571.485710857369;8583.18459746323;7497.550146991198;7597.164311925573;7640.3505702015955;7672.923270767221;7746.419018573517;7399.963000184965;7428.257174282745;7458.291462311072;7482.588457634883;7678.61606919663;6832.93167068323;6960.965195173992;6974.058502102354;6976.197737260077;6976.19773726028;4933.260010998379;5043.394792624908;5070.873228169326;5105.846824595268;5163.638545301793;4976.800927962841;4992.151334273255;5147.6911385317;5171.095058364695;5258.263843061898 09:57:01 INFO - PROCESS | 1656 | |4;prototype.html;268.3613001057483;296.2608292310685;301.9305559721264;309.40439653666687;313.49370766212576;4825.2279635258365;5012.694160686085;5288.60335474839;5376.274202982591;5416.864578385543;4326.555496144676;5007.699538027721;5112.8977420451665;5223.477652234775;5245;4019.557848636655;4031.5363733170766;4041.6160464755803;4081.8163182656854;4086.734362266458;3717.8474672851366;4012.61880121388;4039.6364327210545;4049.250888585621;4070.287699652564;3707.7589956652864;3732.7387082904206;3789.2989796887528;4011.2579172853116;4016.5180178378637;4000.799960002003;4008.819603117867;4011;4019.577944315863;4041.3937909313545;3328.5506456628286;3504.6495350465007;3515.736319776027;3525.6121826599056;3537.2748586539956;4726.291056341542;4749.527646429618;4753.789487888149;5123.513266239701;5136.04983078128;3625.728070394731;4501.954980450187;4520.304885667874;4534.229181039232;4539.432570928634;5496.7526461309335;5502.889942201134;5503.367112782002;5507.531859791922;5562.16567514872;3561.6972557332656;3603.621619729943;3789.6399842014966;3790.1851102013197;3825.6939444844625;4633.976830115827;5035.798568057273;5128.512791284822;5493.48929044512;5785.276840394993;3742.6631603155847;3767.246550689873;3781.962180378216;3793.368851393872;3793.463647222879;3864.381698928158;3869.258833524925;3876.612338766101;3894.4937158169255;3910.569837317893;3319.535265062893;4273.743575385487;4520.254158063883;4542.931856022163;4568.543145685405;4861.392325959255;5155;5377.865553361197;5573.467296493497;5602.439756024365;1283.8587755346905;1310.652677040585;1311.7341765196632;1336.1849271944106;1338.852726200117;717.5658726470515;745.5787480073741;794.412135020081;849.8937632795901;894.7807787092074;5139.922901156486;5159.509846564571;5167.793288268427;5222.582193424517;5223.242629818731;5606.7572970812;5611.2144299798065;5635.3237611486065;5642.548596112383;5676.0918253080135;3946.7039972002217;3971.5234171899556;4946.826861059845;5172.715500647425;5185;2595.3122422558035;2799.572218168708;2941.176470588206;2963.7475518605584;2973.5539669049817;5217.530422261939;5768.682722450221;5807.209052531678;5834.883302333929;5856.824295271149;4565.497795242521;5399.89506034032;5743.08110702294;5783.826485205451;5797.550612346913;5087.872803179876;5298.894022119536;5353.357597088374;5599.332437253524;5783.768649254067;4898.510148985216;5314.7874085036165;5420.620556561003;5591.496765291144;5625.887482250414;4574.4053273075615;4628.768561571976;5635.943640563706;5662.348829884616;5705.714714264187;5967.940320596738;5968.8806223876145;6024.397560244116;6028.819135425944;6058.091286307089;3455.9308813824086;3514.8594056237466;3606.9458958116174;3640.945385819268;3839.00185951649;5443.755031023693;5711.942880571141;5722.313322401338;5792.102224155263;5825.320352637834;4944.711766675265;5267.446918073547;5485.50630443262;5493.846292278667;5530.5022547970875;4915.361003069578;5244.967886438115;5447.536959358499;5491.450854914477;5523.723813809213;4191.895202619959;5323.376370206969;5380.919286210788;5388.973055134699;5413.458654134555;4812.951870481251;4895.118878601744;4921.138800709933;5361.758720857649;5410.540104091039;4928.211486162197;5077.593792496517;5422.024035673617;5441.972790136024;5454.918176227439 09:57:01 INFO - PROCESS | 1656 | |5;yui.html;692.6675195905965;716.3445447415612;750.2834792772902;794.4002278279897;808.3088958940104;15584.454544090959;16208.432704855317;16213.432529861457;16248.51254462365;16274.186290685462;11997.920187183152;14110.858891411082;14159.292035398215;14219.644508887282;14690.55928322152;667.9899801502982;687.6871023684212;827.871679889615;833.6207025803245;860.1699360117485;815.7185770909027;823.8269963307682;825.2531459029558;825.6821123867325;828.7679449754048;907.6097278170384;911.1571796088591;911.2118017914522;911.886014248219;912.2838571721201;700.1528150937321;873.8077622922947;903.0698380667923;904.7150147703452;914.2914241462853;596.9850753731148;658.6574981009848;677.4275737002274;677.5595862689242;677.8305423644089;14979.70040599182;15016.399344026226;15021.699566008729;15067.17130557819;15110.39558417662;13844.446222151202;14040.1575905446;14144.929275353661;14168.079074860205;14362.497312594009;12919.095663303573;13108.737825243537;13172.802407963889;13341.733165336638;13533.729325413437;4486.125205584913;4560.247559152735;4674.766261686936;4682.7658617069355;4787.712737235742;110546;111148;111377.44311278392;111456;111678.44160779225;9903.158231550398;10995.450227488594;11043.944780276208;11365.772684546262;11431.14266430021;22286.219982300543;22288.55422891533;22329.665055024187;22381.55236895253;22417.551648966928;7632.80917977055;7889.09275433321;7956.880646790303;8131.471454355448;8164.6325915333955;4321.611055005064;4385.386045953569;4400.515943246241;4411.228035093845;4577.519360467103;3436.60479044908;3577.5885773136424;3578.7315951303235;3584.426491761306;3589.2462582857893;626.7586979013022;635.2630948100274;638.5051585021553;639.597053856077;646.8188907106017;588.3645662684377;595.7587177193244;603.7343568829701;608.3247595169358;645.2160624840841;8227.382946278933;8756.891303587263;9101.317401194805;9122.452652840851;9287.674931377369;529.1982646290872;564.6504853400377;590.2179612014098;625.3996163682824;630.5460068750492;4262.577977010987;4389.495208051051;4423.093265880487;4442.311441726538;4481.395011673382;97248;142920.85618715727;157643;162529.3747062538;166827.1658641699;133352;133522;133821;135664.32167839294;138804.30597846946;97519.2480751919;119445.40277298732;120992;123363;124275.37862310812;8538.44500107491;8544.487330759926;8559.914400855912;8568;8696.735857709808;52099.47900520946;64829.02756458562;66939;67527.24727527208;69227;83473;91927.16145676916;98187.01812981779;103272.48363758133;105620.94379056111;13049.869501304865;13156.73686526245;13317.134386264861;13497.257650828906;13918.729565623686;12408.379581021094;14948.25258737037;15064.6987060256;15171.364199280788;15470.14914179686;11580.768384632429;13775.242361669803;13981.091229070078;14139.151650900978;14263.572092837232;13733.313334333443;13745.518906838213;13782.241976690977;14040.368183431974;14090.859091408955;13387.464501419834;13502.598381565329;13773.862261377259;13777.586672399844;14207.502737404142;10281.2803103785;10316.587336506456;10386.896131038593;10400.375977441377;10604.098651614699;11299.943500282774;11328.433578321215;11385.203035787747;11608.6517404478;11677.591284305348 09:57:01 INFO - PROCESS | 1656 | __end_tp_report 09:57:01 INFO - PROCESS | 1656 | __start_cc_report 09:57:01 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1247 09:57:01 INFO - PROCESS | 1656 | __end_cc_report 09:57:01 INFO - PROCESS | 1656 | __startTimestamp1472749021334__endTimestamp 09:57:01 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | ------- Summary: start ------- 09:57:01 INFO - PROCESS | 1656 | Number of tests: 6 09:57:01 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5769.98 Median:5907.76 stddev:647.47 (11.0%) stddev-sans-first:617.61 09:57:01 INFO - PROCESS | 1656 | Values: 3213.0 3353.2 3390.6 3421.9 3441.1 5662.9 5689.1 5738.2 5797.9 5804.1 5750.6 5764.3 5859.8 5861.3 5907.8 5152.2 5153.3 5229.6 5239.2 5327.0 5326.2 5330.9 5351.8 5355.4 5379.5 5204.6 5224.9 5242.7 5260.1 5285.1 5272.0 5275.5 5284.2 5287.7 5293.4 5170.4 5191.8 5240.9 5262.3 5269.5 5281.9 5506.8 5719.8 5832.3 5837.2 6244.6 6277.1 6277.6 6312.6 6340.7 6264.7 6300.1 6306.1 6324.0 6343.7 5719.2 5743.5 5784.7 5812.1 5829.1 6280.7 6284.1 6298.2 6298.9 6300.4 6210.3 6216.7 6225.5 6229.7 6230.2 6123.1 6220.0 6280.3 6284.1 6328.0 6281.3 6295.2 6298.1 6300.0 6313.3 6170.3 6314.9 6332.6 6336.3 6351.7 4121.2 4903.8 4907.4 4959.4 4966.9 4484.0 4511.8 4513.9 4520.9 4522.4 5799.4 5808.2 5811.8 5824.6 5828.1 6255.5 6257.5 6313.4 6332.1 6334.5 5854.3 5862.9 5864.7 5873.1 5880.5 5453.5 5455.3 5458.1 5472.1 5472.3 6201.3 6239.0 6259.6 6262.9 6350.6 6335.2 6341.4 6346.7 6349.4 6363.1 6320.9 6321.7 6328.2 6333.3 6342.9 5057.7 5751.6 5986.4 6127.8 6148.5 6170.5 6341.2 6363.3 6364.8 6373.8 6049.8 6058.2 6079.5 6082.6 6085.1 5899.1 5985.2 6045.5 6052.0 6083.2 5892.4 5931.2 5947.3 5951.8 5992.0 6054.4 6075.8 6096.0 6096.3 6120.3 5758.8 5857.4 5885.1 5886.1 5949.6 09:57:01 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:70304.12 Median:18725.48 stddev:157329.69 (840.2%) stddev-sans-first:157683.69 09:57:01 INFO - PROCESS | 1656 | Values: 480.5 496.9 522.9 532.5 539.9 25912.0 36504.3 42762.8 43447.3 43650.5 42991.0 43353.8 43358.8 43420.1 43432.0 8826.7 9696.5 9888.0 9895.1 9900.0 11968.6 12247.7 12264.1 12410.6 12411.7 11984.5 12039.9 12060.5 12067.3 12093.8 12258.8 12315.5 12317.3 12331.7 12359.1 12082.3 12103.9 12228.3 12229.3 12231.4 33323.8 40719.6 40850.6 40922.8 40935.8 2845.5 3279.8 3349.5 3379.9 3474.7 30065.5 30553.7 31592.2 31624.0 31716.0 3278.3 3303.8 3360.9 3410.0 3413.9 493328.0 497090.0 499172.0 499313.0 500878.0 33090.0 34180.0 61745.1 62404.4 62430.8 29575.0 34474.0 34578.5 34725.3 34769.3 14289.4 14431.6 14434.1 14440.8 14451.8 11211.9 11412.7 12272.4 12289.0 12387.6 7739.5 7790.7 7809.6 7868.6 7914.2 3225.7 3241.9 3245.3 3263.9 3287.1 918.4 966.6 1021.9 1024.0 1066.3 27310.0 29936.3 30149.9 30371.4 30407.2 1005.4 1032.8 1062.2 1086.4 1127.0 15187.0 15534.3 15612.0 15753.0 15867.0 667530.0 669450.0 671373.3 674588.0 675698.0 57501.0 58114.0 58153.4 58214.0 58309.0 1194.2 1237.5 1238.0 1335.6 1338.1 20506.7 30742.5 31314.4 32607.7 33160.7 54096.0 54641.5 54738.7 54827.0 54860.0 575087.0 580439.0 580669.0 580752.1 582093.0 26320.7 26561.5 26925.3 27131.3 28049.9 46768.0 46788.8 47924.0 47952.8 48204.0 38899.0 39124.8 39601.8 40718.2 40811.4 34041.0 38844.0 39051.0 39899.8 40540.6 37093.0 37638.6 38492.0 38705.0 38784.0 32166.4 34425.5 34595.7 34714.5 34886.8 25480.7 25600.6 25671.4 26049.3 26252.2 09:57:01 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:26758.74 Median:34006.42 stddev:46931.65 (138.0%) stddev-sans-first:47021.43 09:57:01 INFO - PROCESS | 1656 | Values: 353.2 370.0 371.5 380.2 400.8 15247.8 16163.6 19971.8 20408.9 20544.5 20592.5 21300.5 21315.6 21323.0 22169.2 6786.8 6816.1 7017.5 7103.9 7163.7 4629.3 6958.4 7050.8 7058.0 7059.6 4710.3 8034.3 8047.8 8059.4 8130.3 7765.8 8028.8 8040.8 8128.4 8216.5 7548.0 7810.9 7887.6 7909.8 7923.0 24168.2 29270.3 29443.4 29526.9 29660.0 2309.4 2395.2 2496.2 2742.7 3224.7 16535.8 16857.5 16860.5 17656.8 17692.6 1840.9 1965.6 1992.0 2056.8 2069.4 129183.4 144722.0 147362.0 150591.0 150644.0 19811.9 21224.5 21248.3 21287.6 21453.0 31409.4 32984.8 35028.0 35094.8 35229.0 9449.9 9586.9 10585.8 10686.0 10784.8 8663.8 8671.4 8679.1 8850.4 8860.4 7975.1 9376.8 9450.4 9565.9 9647.0 2927.8 3623.4 3682.3 3689.4 3798.8 847.6 867.7 934.5 955.9 973.8 19490.3 20313.8 20525.4 20655.8 20905.3 886.4 912.6 939.2 940.7 996.6 8069.4 8960.5 10736.1 10782.7 10800.6 232759.0 232797.0 233019.7 233216.0 234444.0 100834.0 127781.0 130413.0 130425.7 132612.0 926.9 943.3 964.7 966.9 989.2 14783.4 18095.5 18221.0 18239.1 18481.0 37945.6 39308.8 53940.0 53980.0 55982.0 73095.6 73600.5 73825.3 76139.0 76518.6 7187.6 7308.6 7530.3 8000.7 8231.5 9334.7 12841.9 13470.3 13472.3 13714.5 11313.5 11384.0 11727.8 11737.4 11842.6 9707.6 9755.3 11149.8 11232.9 11684.7 11357.5 11451.3 11834.7 11867.6 11886.5 8077.8 8205.3 8326.8 8412.8 8560.1 10520.6 11260.8 11655.5 11662.2 11731.4 09:57:01 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5749.20 Median:5017.77 stddev:3222.05 (64.2%) stddev-sans-first:3215.44 09:57:01 INFO - PROCESS | 1656 | Values: 1524.7 1533.2 1759.4 1910.3 1911.2 8528.8 9053.5 9060.2 9077.0 9080.0 7845.8 8093.4 8507.3 8767.5 8846.0 2079.3 2365.4 2402.4 2403.4 2409.4 2366.6 2384.4 2406.3 2411.1 2420.4 2376.3 2394.5 2404.1 2409.8 2413.6 2371.2 2383.8 2391.0 2392.6 2400.9 2369.8 2387.1 2392.4 2392.9 2394.5 8707.3 8787.4 8850.9 8869.6 8880.4 5169.9 5314.5 5317.6 5323.3 5328.8 3610.3 3614.4 3666.5 3675.3 3705.4 1006.9 1088.2 1088.2 1092.4 1112.0 11643.3 11735.5 11775.9 11797.5 11815.3 4499.8 4589.6 4766.9 4770.8 4782.2 9865.5 9867.6 9879.3 9884.9 9920.4 5324.7 5327.1 5331.8 5332.4 5335.4 4240.1 4390.5 4547.8 4872.2 4891.7 3572.3 3701.9 3806.0 3973.9 3988.2 1277.2 1294.9 1301.4 1316.4 1316.5 827.7 861.0 890.7 894.7 927.9 5047.2 5592.1 5695.0 5945.4 5968.5 839.9 902.9 942.2 942.5 948.6 3800.5 4016.1 4096.8 4099.4 4102.7 10525.4 10639.6 10768.0 10814.5 10957.7 10683.7 10903.6 10907.0 10912.8 10921.6 7786.0 8139.3 8207.0 8241.0 8322.0 5938.9 5999.6 6088.3 6091.3 6132.3 9899.7 10106.5 10160.7 10166.5 10178.4 9730.3 10387.5 10387.6 10388.5 10404.2 7000.7 7220.5 7612.4 7745.1 7747.2 8231.8 8355.0 8504.9 8571.5 8583.2 7497.6 7597.2 7640.4 7672.9 7746.4 7400.0 7428.3 7458.3 7482.6 7678.6 6832.9 6961.0 6974.1 6976.2 6976.2 4933.3 5043.4 5070.9 5105.8 5163.6 4976.8 4992.2 5147.7 5171.1 5258.3 09:57:01 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4440.22 Median:4977.26 stddev:1365.60 (27.4%) stddev-sans-first:1333.04 09:57:01 INFO - PROCESS | 1656 | Values: 268.4 296.3 301.9 309.4 313.5 4825.2 5012.7 5288.6 5376.3 5416.9 4326.6 5007.7 5112.9 5223.5 5245.0 4019.6 4031.5 4041.6 4081.8 4086.7 3717.8 4012.6 4039.6 4049.3 4070.3 3707.8 3732.7 3789.3 4011.3 4016.5 4000.8 4008.8 4011.0 4019.6 4041.4 3328.6 3504.6 3515.7 3525.6 3537.3 4726.3 4749.5 4753.8 5123.5 5136.0 3625.7 4502.0 4520.3 4534.2 4539.4 5496.8 5502.9 5503.4 5507.5 5562.2 3561.7 3603.6 3789.6 3790.2 3825.7 4634.0 5035.8 5128.5 5493.5 5785.3 3742.7 3767.2 3782.0 3793.4 3793.5 3864.4 3869.3 3876.6 3894.5 3910.6 3319.5 4273.7 4520.3 4542.9 4568.5 4861.4 5155.0 5377.9 5573.5 5602.4 1283.9 1310.7 1311.7 1336.2 1338.9 717.6 745.6 794.4 849.9 894.8 5139.9 5159.5 5167.8 5222.6 5223.2 5606.8 5611.2 5635.3 5642.5 5676.1 3946.7 3971.5 4946.8 5172.7 5185.0 2595.3 2799.6 2941.2 2963.7 2973.6 5217.5 5768.7 5807.2 5834.9 5856.8 4565.5 5399.9 5743.1 5783.8 5797.6 5087.9 5298.9 5353.4 5599.3 5783.8 4898.5 5314.8 5420.6 5591.5 5625.9 4574.4 4628.8 5635.9 5662.3 5705.7 5967.9 5968.9 6024.4 6028.8 6058.1 3455.9 3514.9 3606.9 3640.9 3839.0 5443.8 5711.9 5722.3 5792.1 5825.3 4944.7 5267.4 5485.5 5493.8 5530.5 4915.4 5245.0 5447.5 5491.5 5523.7 4191.9 5323.4 5380.9 5389.0 5413.5 4813.0 4895.1 4921.1 5361.8 5410.5 4928.2 5077.6 5422.0 5442.0 5454.9 09:57:01 INFO - PROCESS | 1656 | 09:57:01 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25419.93 Median:3578.16 stddev:40820.38 (1140.8%) stddev-sans-first:40892.67 09:57:01 INFO - PROCESS | 1656 | Values: 692.7 716.3 750.3 794.4 808.3 15584.5 16208.4 16213.4 16248.5 16274.2 11997.9 14110.9 14159.3 14219.6 14690.6 668.0 687.7 827.9 833.6 860.2 815.7 823.8 825.3 825.7 828.8 907.6 911.2 911.2 911.9 912.3 700.2 873.8 903.1 904.7 914.3 597.0 658.7 677.4 677.6 677.8 14979.7 15016.4 15021.7 15067.2 15110.4 13844.4 14040.2 14144.9 14168.1 14362.5 12919.1 13108.7 13172.8 13341.7 13533.7 4486.1 4560.2 4674.8 4682.8 4787.7 110546.0 111148.0 111377.4 111456.0 111678.4 9903.2 10995.5 11043.9 11365.8 11431.1 22286.2 22288.6 22329.7 22381.6 22417.6 7632.8 7889.1 7956.9 8131.5 8164.6 4321.6 4385.4 4400.5 4411.2 4577.5 3436.6 3577.6 3578.7 3584.4 3589.2 626.8 635.3 638.5 639.6 646.8 588.4 595.8 603.7 608.3 645.2 8227.4 8756.9 9101.3 9122.5 9287.7 529.2 564.7 590.2 625.4 630.5 4262.6 4389.5 4423.1 4442.3 4481.4 97248.0 142920.9 157643.0 162529.4 166827.2 133352.0 133522.0 133821.0 135664.3 138804.3 97519.2 119445.4 120992.0 123363.0 124275.4 8538.4 8544.5 8559.9 8568.0 8696.7 52099.5 64829.0 66939.0 67527.2 69227.0 83473.0 91927.2 98187.0 103272.5 105620.9 13049.9 13156.7 13317.1 13497.3 13918.7 12408.4 14948.3 15064.7 15171.4 15470.1 11580.8 13775.2 13981.1 14139.2 14263.6 13733.3 13745.5 13782.2 14040.4 14090.9 13387.5 13502.6 13773.9 13777.6 14207.5 10281.3 10316.6 10386.9 10400.4 10604.1 11299.9 11328.4 11385.2 11608.7 11677.6 09:57:01 INFO - PROCESS | 1656 | -------- Summary: end -------- 09:57:01 INFO - PROCESS | 1656 | 09:57:01 INFO - TEST-INFO | 1656: exit 0 09:57:01 INFO - TEST-OK | dromaeo_css | took 1118712ms 09:57:01 INFO - TEST-START | kraken 09:57:01 INFO - operating with platform_type : mac_ 09:57:01 INFO - Initialising browser for kraken test... 09:57:01 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpa7HRor/profile http://localhost:49262/getInfo.html) 09:57:04 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 09:57:04 INFO - PROCESS | 1662 | colorDepth:24 09:57:04 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 09:57:04 INFO - PROCESS | 1662 | __metrics 09:57:04 INFO - PROCESS | 1662 | 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" 09:57:04 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:04 INFO - TEST-INFO | 1662: exit 0 09:57:04 INFO - Browser initialized. 09:57:04 INFO - Running cycle 1/1 for kraken test... 09:57:04 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpa7HRor/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:16 INFO - PROCESS | 1663 | RSS: Main: 233992192 09:57:16 INFO - PROCESS | 1663 | 09:57:19 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:20 INFO - PROCESS | 1663 | RSS: Main: 292720640 09:57:20 INFO - PROCESS | 1663 | 09:57:22 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-dft) 09:57:23 INFO - PROCESS | 1663 | RSS: Main: 289206272 09:57:23 INFO - PROCESS | 1663 | 09:57:26 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-fft) 09:57:27 INFO - PROCESS | 1663 | RSS: Main: 379273216 09:57:27 INFO - PROCESS | 1663 | 09:57:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator) 09:57:30 INFO - PROCESS | 1663 | RSS: Main: 281403392 09:57:30 INFO - PROCESS | 1663 | 09:57:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:33 INFO - PROCESS | 1663 | RSS: Main: 284725248 09:57:33 INFO - PROCESS | 1663 | 09:57:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:36 INFO - PROCESS | 1663 | RSS: Main: 323121152 09:57:36 INFO - PROCESS | 1663 | 09:57:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:39 INFO - PROCESS | 1663 | RSS: Main: 322224128 09:57:39 INFO - PROCESS | 1663 | 09:57:42 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial) 09:57:43 INFO - PROCESS | 1663 | RSS: Main: 322945024 09:57:43 INFO - PROCESS | 1663 | 09:57:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:45 INFO - PROCESS | 1663 | RSS: Main: 297132032 09:57:45 INFO - PROCESS | 1663 | 09:57:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:47 INFO - PROCESS | 1663 | RSS: Main: 302981120 09:57:47 INFO - PROCESS | 1663 | 09:57:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:50 INFO - PROCESS | 1663 | RSS: Main: 303988736 09:57:50 INFO - PROCESS | 1663 | 09:57:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:54 INFO - PROCESS | 1663 | RSS: Main: 319963136 09:57:54 INFO - PROCESS | 1663 | 09:57:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:58 INFO - PROCESS | 1663 | RSS: Main: 316166144 09:57:58 INFO - PROCESS | 1663 | 09:57:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49262/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49262/tests/kraken/driver.html?testName=ai-astar) 09:58:00 INFO - PROCESS | 1663 | RSS: Main: 360030208 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | __start_tp_report 09:58:00 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 09:58:00 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 09:58:00 INFO - PROCESS | 1663 | |i|pagename|runs| 09:58:00 INFO - PROCESS | 1663 | |0;ai-astar;114;115;116;114;115;114;114;114;114;122 09:58:00 INFO - PROCESS | 1663 | |1;audio-beat-detection;144;112;128;161;109;127;127;108;158;108 09:58:00 INFO - PROCESS | 1663 | |2;audio-dft;185;183;184;183;186;185;185;181;181;184 09:58:00 INFO - PROCESS | 1663 | |3;audio-fft;75;75;80;152;59;61;60;69;156;58 09:58:00 INFO - PROCESS | 1663 | |4;audio-oscillator;82;82;132;71;69;79;121;90;97;144 09:58:00 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;88;87;90;87;88;85;89;87;88;86 09:58:00 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;95;95;95;95;95;95;94;93;95 09:58:00 INFO - PROCESS | 1663 | |7;imaging-desaturate;86;83;84;84;83;84;83;84;84;85 09:58:00 INFO - PROCESS | 1663 | |8;json-parse-financial;57;56;56;55;53;55;57;56;54;54 09:58:00 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;64;67;73;66;70;76;73;69;70;72 09:58:00 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;86;89;85;85;87;88;84;86;90;90 09:58:00 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;146;146;139;137;142;140;139;138;182;139 09:58:00 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;175;240;190;177;179;178;177;182;176;175 09:58:00 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;71;68;69;66;70;70;69;70;68;70 09:58:00 INFO - PROCESS | 1663 | __end_tp_report 09:58:00 INFO - PROCESS | 1663 | __start_cc_report 09:58:00 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2010 09:58:00 INFO - PROCESS | 1663 | __end_cc_report 09:58:00 INFO - PROCESS | 1663 | __startTimestamp1472749080008__endTimestamp 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | ------- Summary: start ------- 09:58:00 INFO - PROCESS | 1663 | Number of tests: 14 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:115.20 Median:114.50 stddev:2.49 (2.2%) stddev-sans-first:2.60 09:58:00 INFO - PROCESS | 1663 | Values: 114.0 115.0 116.0 114.0 115.0 114.0 114.0 114.0 114.0 122.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:127.50 stddev:20.18 (15.8%) stddev-sans-first:20.57 09:58:00 INFO - PROCESS | 1663 | Values: 144.0 112.0 128.0 161.0 109.0 127.0 127.0 108.0 158.0 108.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:1.70 (0.9%) stddev-sans-first:1.74 09:58:00 INFO - PROCESS | 1663 | Values: 185.0 183.0 184.0 183.0 186.0 185.0 185.0 181.0 181.0 184.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:84.50 Median:65.00 stddev:37.44 (57.6%) stddev-sans-first:39.55 09:58:00 INFO - PROCESS | 1663 | Values: 75.0 75.0 80.0 152.0 59.0 61.0 60.0 69.0 156.0 58.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:80.50 stddev:26.44 (32.8%) stddev-sans-first:27.50 09:58:00 INFO - PROCESS | 1663 | Values: 82.0 82.0 132.0 71.0 69.0 79.0 121.0 90.0 97.0 144.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:87.50 Median:88.00 stddev:1.43 (1.6%) stddev-sans-first:1.51 09:58:00 INFO - PROCESS | 1663 | Values: 88.0 87.0 90.0 87.0 88.0 85.0 89.0 87.0 88.0 86.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:58:00 INFO - PROCESS | 1663 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 93.0 95.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:0.94 (1.1%) stddev-sans-first:0.67 09:58:00 INFO - PROCESS | 1663 | Values: 86.0 83.0 84.0 84.0 83.0 84.0 83.0 84.0 84.0 85.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:1.34 (2.4%) stddev-sans-first:1.27 09:58:00 INFO - PROCESS | 1663 | Values: 57.0 56.0 56.0 55.0 53.0 55.0 57.0 56.0 54.0 54.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:71.00 stddev:3.65 (5.1%) stddev-sans-first:3.16 09:58:00 INFO - PROCESS | 1663 | Values: 64.0 67.0 73.0 66.0 70.0 76.0 73.0 69.0 70.0 72.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.50 stddev:2.16 (2.5%) stddev-sans-first:2.26 09:58:00 INFO - PROCESS | 1663 | Values: 86.0 89.0 85.0 85.0 87.0 88.0 84.0 86.0 90.0 90.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:141.00 stddev:13.44 (9.5%) stddev-sans-first:14.25 09:58:00 INFO - PROCESS | 1663 | Values: 146.0 146.0 139.0 137.0 142.0 140.0 139.0 138.0 182.0 139.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.90 Median:178.50 stddev:19.87 (11.1%) stddev-sans-first:20.75 09:58:00 INFO - PROCESS | 1663 | Values: 175.0 240.0 190.0 177.0 179.0 178.0 177.0 182.0 176.0 175.0 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:1.45 (2.1%) stddev-sans-first:1.36 09:58:00 INFO - PROCESS | 1663 | Values: 71.0 68.0 69.0 66.0 70.0 70.0 69.0 70.0 68.0 70.0 09:58:00 INFO - PROCESS | 1663 | -------- Summary: end -------- 09:58:00 INFO - PROCESS | 1663 | 09:58:00 INFO - TEST-INFO | 1663: exit 0 09:58:00 INFO - TEST-OK | kraken | took 58926ms 09:58:00 INFO - SUITE-END | took 1177s 09:58:01 INFO - Completed test suite (00:19:37) 09:58:01 INFO - javascript benchmark 09:58:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3213.0, 3353.211995181133, 3390.627031026585, 3421.914452138698, 3441.1397150712323, 5662.858428539289, 5689.061304884694, 5738.16796564499, 5797.855053623662, 5804.071348584227, 5750.6262092963925, 5764.308283006045, 5859.824205273838, 5861.267341582302, 5907.763689452417, 5152.201408781626, 5153.2785410042625, 5229.555487783524, 5239.187925871496, 5326.973365133169, 5326.201069839516, 5330.946690533084, 5351.785928562853, 5355.357357117132, 5379.542738867201, 5204.5576126028955, 5224.947750522484, 5242.659227150261, 5260.079486089938, 5285.101532739443, 5271.998320319165, 5275.498827611371, 5284.233786101032, 5287.735613219324, 5293.444188360205, 5170.405403378622, 5191.818286359997, 5240.895182096375, 5262.2632831403635, 5269.473052694738, 5281.9207711884355, 5506.834794956158, 5719.828405147852, 5832.270966129234, 5837.153612726131, 6244.594101383395, 6277.121203031579, 6277.623342599459, 6312.558120931537, 6340.746370145189, 6264.686765661744, 6300.054991751228, 6306.1171435998995, 6324.0, 6343.7145328459865, 5719.170720245625, 5743.454371834628, 5784.739686714108, 5812.099124635688, 5829.09649004405, 6280.748770049238, 6284.088807123033, 6298.1812364392335, 6298.905516417258, 6300.3699630037545, 6210.31686514483, 6216.720247588869, 6225.501959843202, 6229.688515574203, 6230.221222347207, 6123.069106197319, 6219.968900155471, 6280.2777680566405, 6284.12022316876, 6327.96836015817, 6281.277653069864, 6295.181626388632, 6298.055291706189, 6299.992001279773, 6313.273973493016, 6170.293778773391, 6314.905276420857, 6332.556721029576, 6336.3030066693545, 6351.650659213788, 4121.175764847043, 4903.803847846046, 4907.435644900811, 4959.404871415453, 4966.875828104254, 4484.013517026249, 4511.751853648081, 4513.909721805546, 4520.88697782558, 4522.389477420506, 5799.420057994167, 5808.186853840466, 5811.767529298865, 5824.563157763185, 5828.067509198592, 6255.46828519581, 6257.499400047986, 6313.40022697843, 6332.050192471166, 6334.461570766536, 5854.268216472941, 5862.882742345129, 5864.67744274069, 5873.089671100986, 5880.470757631833, 5453.454654534515, 5455.318085239345, 5458.1266997280245, 5472.124460086367, 5472.34331880169, 6201.255849298113, 6239.0, 6259.624422534663, 6262.937370626236, 6350.587211831216, 6335.239771227298, 6341.4292713656, 6346.7461301549265, 6349.365063493614, 6363.1091647168505, 6320.936790632035, 6321.683915804283, 6328.177336946352, 6333.2716737575765, 6342.873142537216, 5057.721825299644, 5751.626144300607, 5986.431289027535, 6127.816165515042, 6148.50811935053, 6170.50635949132, 6341.239051313872, 6363.331850155852, 6364.77723279683, 6373.808785736435, 6049.78825741097, 6058.212432383762, 6079.452849243589, 6082.574219804748, 6085.148079268817, 5899.144624029577, 5985.221921150222, 6045.546584006129, 6052.03167493199, 6083.178770865877, 5892.351841297368, 5931.169636250842, 5947.345791962967, 5951.791687290924, 5992.0, 6054.3642917493025, 6075.817725468243, 6095.969520152371, 6096.298925623432, 6120.296165940974, 5758.798442054508, 5857.355690873916, 5885.14665373527, 5886.117082437669, 5949.553783466344], "value": 5727.997323847275, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.4714813704925, 496.9279454479101, 522.932018837551, 532.5180711456132, 539.897419490297, 25912.0, 36504.26991460169, 42762.78618606907, 43447.34828977568, 43650.472233471875, 42991.0, 43353.78323108388, 43358.78320608393, 43420.13159736803, 43432.0, 8826.735197944072, 9696.515174241278, 9888.01119888009, 9895.059969302905, 9899.950500247489, 11968.640940771791, 12247.69380765479, 12264.080193985443, 12410.627681169579, 12411.689707757288, 11984.520619175222, 12039.939800300986, 12060.517579296818, 12067.336296503689, 12093.818592721116, 12258.816117758197, 12315.5073797048, 12317.260964342167, 12331.656985172789, 12359.13486055972, 12082.33547154906, 12103.878961210363, 12228.266304021787, 12229.327386993711, 12231.449584768618, 33323.83338083318, 40719.59280407188, 40850.591494084976, 40922.79538602288, 40935.771926842244, 2845.502037143512, 3279.8032118072992, 3349.4975753636904, 3379.9155021124425, 3474.722022238215, 30065.549016764766, 30553.694463055308, 31592.210194745086, 31624.0, 31716.04851854425, 3278.2623909620384, 3303.8182899940493, 3360.899173024813, 3409.9659003410143, 3413.8634454621783, 493328.0, 497090.0, 499172.0, 499313.0, 500877.9912200904, 33090.00729978104, 34180.0, 61745.07382389268, 62404.375956240765, 62430.75138497295, 29575.0, 34474.0, 34578.48132278018, 34725.30549389048, 34769.30461390758, 14289.356978935974, 14431.567052988426, 14434.061785984088, 14440.783388249185, 14451.78322325166, 11211.943940280247, 11412.657620271406, 12272.38638068093, 12289.016878649687, 12387.566435174725, 7739.535627862347, 7790.6728443802085, 7809.570473623891, 7868.645910934022, 7914.16901225363, 3225.7258133058954, 3241.8703251870133, 3245.3184831185245, 3263.853126609308, 3287.112479630486, 918.4213945214606, 966.5602151021268, 1021.9473941839917, 1024.0476356987924, 1066.269605320358, 27309.952813138978, 29936.25159371033, 30149.865121390485, 30371.392572148874, 30407.23981900426, 1005.3615953869346, 1032.8244198486275, 1062.2139616683598, 1086.440483151178, 1126.9943650281696, 15187.0, 15534.300956456986, 15612.0, 15752.976056556286, 15866.968647037906, 667530.0, 669450.0, 671373.2862671311, 674588.0, 675698.0, 57501.0, 58114.0, 58153.418465815645, 58214.0, 58309.0, 1194.2416565480808, 1237.4988129807623, 1238.0095923261536, 1335.5592654424017, 1338.1435881036145, 20506.692399614316, 30742.538861917536, 31314.373712525165, 32607.673923260463, 33160.67357305681, 54096.0, 54641.453585463634, 54738.726306369805, 54827.0, 54860.0, 575087.0, 580439.0, 580669.0, 580752.096239533, 582093.0, 26320.73679263183, 26561.46877062409, 26925.3268668277, 27131.321716956445, 28049.859750701933, 46768.0, 46788.7660561695, 47924.0, 47952.7602361986, 48204.0, 38899.0, 39124.80437597794, 39601.80199098986, 40718.185636286515, 40811.38782918199, 34041.0, 38844.028899276615, 39051.0, 39899.80050099847, 40540.594594053684, 37093.0, 37638.623613764605, 38492.0, 38705.0, 38784.0, 32166.35667286688, 34425.48361774525, 34595.71541076415, 34714.47928281027, 34886.82556587201, 25480.745192547838, 25600.615990760525, 25671.358216044748, 26049.348766280993, 26252.21243362702], "value": 19128.586883445372, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.19295409988183, 369.9889003329901, 371.5058971567815, 380.2129591745087, 400.781574042147, 15247.771283430744, 16163.595910102253, 19971.800281997177, 20408.8979555102, 20544.486387840294, 20592.485187870312, 21300.4674883128, 21315.57368852626, 21323.0, 22169.2240771573, 6786.7963961080995, 6816.147981502312, 7017.473689473284, 7103.89344159838, 7163.749268775594, 4629.259318509039, 6958.443324534025, 7050.7532236371735, 7057.964710176442, 7059.647017649123, 4710.26990816424, 8034.276915077642, 8047.798805029892, 8059.395545334094, 8130.349572034253, 7765.844683106363, 8028.759137225892, 8040.8391832163325, 8128.4310098293145, 8216.46592971461, 7548.0, 7810.882836757453, 7887.566183859885, 7909.802254943615, 7922.960385198095, 24168.154114605903, 29270.268243293875, 29443.411131777244, 29526.85236573825, 29659.96190133335, 2309.3880121767743, 2395.2215529952837, 2496.213692686798, 2742.698303186648, 3224.67753224678, 16535.83464165355, 16857.49427517164, 16860.494185174466, 17656.823431765646, 17692.557686057822, 1840.9079546022645, 1965.6363572738946, 1991.9525118521158, 2056.8354531637433, 2069.3895300886275, 129183.35408322899, 144721.99999999895, 147362.0, 150591.0, 150644.0, 19811.900940495205, 21224.469388265417, 21248.25631102904, 21287.574248514942, 21453.0, 31409.371812563622, 32984.8455304068, 35028.0, 35094.82452587721, 35229.0, 9449.866016078115, 9586.856197157049, 10585.788284234271, 10686.0, 10784.838227426595, 8663.783405414915, 8671.393002489891, 8679.132086791396, 8850.424722393023, 8860.3797734158, 7975.122736498981, 9376.765580860532, 9450.43297402158, 9565.856512152322, 9646.951765241129, 2927.780416468773, 3623.387592521303, 3682.3371793077504, 3689.3543629864666, 3798.8290526926353, 847.5804476783908, 867.7483529776421, 934.4580143516744, 955.9283053770995, 973.8490533967247, 19490.317838875853, 20313.78117312966, 20525.38423847287, 20655.79344206569, 20905.268315608882, 886.3795343259744, 912.6486302773347, 939.2251392601131, 940.703678341334, 996.566493575297, 8069.354451643854, 8960.507172105597, 10736.087432568163, 10782.67651970442, 10800.567977280822, 232759.0, 232797.0, 233019.6698033032, 233216.0, 234444.0, 100834.0, 127781.0, 130413.0, 130425.69574304325, 132612.01081983777, 926.8702381666573, 943.2689665509148, 964.7250533597891, 966.8839739231337, 989.1789814453986, 14783.408663653334, 18095.45713628593, 18221.0, 18239.0880455974, 18480.983545904568, 37945.62054379421, 39308.80345598254, 53940.0, 53980.0, 55982.0, 73095.63452182704, 73600.46318479408, 73825.26174738184, 76139.0, 76518.61740691261, 7187.604681742554, 7308.5614863108385, 7530.322270995636, 8000.719974801087, 8231.547264900488, 9334.71995840126, 12841.935790321362, 13470.259135747629, 13472.259025753678, 13714.451421943011, 11313.490892909675, 11384.032357249727, 11727.824082638597, 11737.413129343327, 11842.644720658394, 9707.563159657691, 9755.317127800985, 11149.777004459704, 11232.887671123512, 11684.70788230301, 11357.4889129991, 11451.255668381862, 11834.704132396415, 11867.58463453775, 11886.524539018343, 8077.7576672699915, 8205.343572514304, 8326.83346333082, 8412.831743365221, 8560.058393576826, 10520.631777887736, 11260.774784504429, 11655.475503602527, 11662.241954272946, 11731.35477548743], "value": 10649.407578205628, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1524.6874390749897, 1533.1950725868921, 1759.3842155245666, 1910.2550005497867, 1911.2259534888376, 8528.786780330494, 9053.50205738684, 9060.229880460161, 9076.954615226923, 9079.954600226989, 7845.80385490363, 8093.352531797457, 8507.276881465068, 8767.517786521754, 8845.955770221157, 2079.313826437272, 2365.38499990003, 2402.423418379594, 2403.3751224681623, 2409.4458274596855, 2366.5503554324623, 2384.3919800450863, 2406.254061241012, 2411.0717373811035, 2420.3707036170545, 2376.263358358914, 2394.473215892501, 2404.086447150089, 2409.795167410772, 2413.613821788514, 2371.1582388252095, 2383.833131680783, 2390.9880450597634, 2392.557376885282, 2400.9279721608204, 2369.7985671217966, 2387.092904696204, 2392.4497365605835, 2392.892319845611, 2394.461246219604, 8707.34694897879, 8787.428817126865, 8850.911490885073, 8869.605206594248, 8880.422772519765, 5169.870753231199, 5314.5216930476445, 5317.574594032468, 5323.307969963918, 5328.760205790749, 3610.2779444111284, 3614.36748569002, 3666.4683620874825, 3675.320065787838, 3705.4441833724886, 1006.9093781559621, 1088.1784252889097, 1088.2491081153978, 1092.4428541443842, 1111.9833202501968, 11643.301401915913, 11735.471903764352, 11775.941120294343, 11797.528098876035, 11815.291082535075, 4499.7975091120325, 4589.5639914208095, 4766.904661906742, 4770.833020844254, 4782.210935195723, 9865.506724663737, 9867.555959981819, 9879.308448408687, 9884.901150988542, 9920.355176913477, 5324.707141107277, 5327.147656374961, 5331.840044798662, 5332.440093790174, 5335.439778823245, 4240.1440196996655, 4390.495093064338, 4547.795349209294, 4872.19608764557, 4891.6575839691595, 3572.3212589607892, 3701.9089060634133, 3805.9809700951873, 3973.92052158961, 3988.202359528048, 1277.182603133995, 1294.9028822838136, 1301.355828864718, 1316.4339334086435, 1316.5194703932996, 827.6813426830713, 860.979739009276, 890.6570970176529, 894.7494701483595, 927.9350445468888, 5047.242913562922, 5592.133219351007, 5694.971525142348, 5945.40545945402, 5968.522518198533, 839.878761852921, 902.8961669408102, 942.2038377570941, 942.4580866002076, 948.62055177928, 3800.524934383202, 4016.136530645925, 4096.836126554964, 4099.446574712435, 4102.671786257092, 10525.36847789135, 10639.627613033506, 10768.030877221088, 10814.513346899408, 10957.671269861918, 10683.732906677395, 10903.618373356894, 10907.0, 10912.836307455394, 10921.563137474412, 7786.0, 8139.348852091818, 8207.0, 8241.011078670717, 8322.0, 5938.910916336172, 5999.5800293979, 6088.269407671108, 6091.329953705177, 6132.325444201224, 9899.703008909744, 10106.545205465625, 10160.695179144637, 10166.491675416348, 10178.440185789852, 9730.318877678777, 10387.532561034923, 10387.636432724817, 10388.532516036948, 10404.167666586503, 7000.684969176297, 7220.458465614995, 7612.352949999312, 7745.148033716174, 7747.18654541265, 8231.753047408587, 8354.958225208835, 8504.914950850412, 8571.485710857369, 8583.18459746323, 7497.550146991198, 7597.164311925573, 7640.3505702015955, 7672.923270767221, 7746.419018573517, 7399.963000184965, 7428.257174282745, 7458.291462311072, 7482.588457634883, 7678.61606919663, 6832.93167068323, 6960.965195173992, 6974.058502102354, 6976.197737260077, 6976.19773726028, 4933.260010998379, 5043.394792624908, 5070.873228169326, 5105.846824595268, 5163.638545301793, 4976.800927962841, 4992.151334273255, 5147.6911385317, 5171.095058364695, 5258.263843061898], "value": 4621.39652354726, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.3613001057483, 296.2608292310685, 301.9305559721264, 309.40439653666687, 313.49370766212576, 4825.2279635258365, 5012.694160686085, 5288.60335474839, 5376.274202982591, 5416.864578385543, 4326.555496144676, 5007.699538027721, 5112.8977420451665, 5223.477652234775, 5245.0, 4019.557848636655, 4031.5363733170766, 4041.6160464755803, 4081.8163182656854, 4086.734362266458, 3717.8474672851366, 4012.61880121388, 4039.6364327210545, 4049.250888585621, 4070.287699652564, 3707.7589956652864, 3732.7387082904206, 3789.2989796887528, 4011.2579172853116, 4016.5180178378637, 4000.799960002003, 4008.819603117867, 4011.0, 4019.577944315863, 4041.3937909313545, 3328.5506456628286, 3504.6495350465007, 3515.736319776027, 3525.6121826599056, 3537.2748586539956, 4726.291056341542, 4749.527646429618, 4753.789487888149, 5123.513266239701, 5136.04983078128, 3625.728070394731, 4501.954980450187, 4520.304885667874, 4534.229181039232, 4539.432570928634, 5496.7526461309335, 5502.889942201134, 5503.367112782002, 5507.531859791922, 5562.16567514872, 3561.6972557332656, 3603.621619729943, 3789.6399842014966, 3790.1851102013197, 3825.6939444844625, 4633.976830115827, 5035.798568057273, 5128.512791284822, 5493.48929044512, 5785.276840394993, 3742.6631603155847, 3767.246550689873, 3781.962180378216, 3793.368851393872, 3793.463647222879, 3864.381698928158, 3869.258833524925, 3876.612338766101, 3894.4937158169255, 3910.569837317893, 3319.535265062893, 4273.743575385487, 4520.254158063883, 4542.931856022163, 4568.543145685405, 4861.392325959255, 5155.0, 5377.865553361197, 5573.467296493497, 5602.439756024365, 1283.8587755346905, 1310.652677040585, 1311.7341765196632, 1336.1849271944106, 1338.852726200117, 717.5658726470515, 745.5787480073741, 794.412135020081, 849.8937632795901, 894.7807787092074, 5139.922901156486, 5159.509846564571, 5167.793288268427, 5222.582193424517, 5223.242629818731, 5606.7572970812, 5611.2144299798065, 5635.3237611486065, 5642.548596112383, 5676.0918253080135, 3946.7039972002217, 3971.5234171899556, 4946.826861059845, 5172.715500647425, 5185.0, 2595.3122422558035, 2799.572218168708, 2941.176470588206, 2963.7475518605584, 2973.5539669049817, 5217.530422261939, 5768.682722450221, 5807.209052531678, 5834.883302333929, 5856.824295271149, 4565.497795242521, 5399.89506034032, 5743.08110702294, 5783.826485205451, 5797.550612346913, 5087.872803179876, 5298.894022119536, 5353.357597088374, 5599.332437253524, 5783.768649254067, 4898.510148985216, 5314.7874085036165, 5420.620556561003, 5591.496765291144, 5625.887482250414, 4574.4053273075615, 4628.768561571976, 5635.943640563706, 5662.348829884616, 5705.714714264187, 5967.940320596738, 5968.8806223876145, 6024.397560244116, 6028.819135425944, 6058.091286307089, 3455.9308813824086, 3514.8594056237466, 3606.9458958116174, 3640.945385819268, 3839.00185951649, 5443.755031023693, 5711.942880571141, 5722.313322401338, 5792.102224155263, 5825.320352637834, 4944.711766675265, 5267.446918073547, 5485.50630443262, 5493.846292278667, 5530.5022547970875, 4915.361003069578, 5244.967886438115, 5447.536959358499, 5491.450854914477, 5523.723813809213, 4191.895202619959, 5323.376370206969, 5380.919286210788, 5388.973055134699, 5413.458654134555, 4812.951870481251, 4895.118878601744, 4921.138800709933, 5361.758720857649, 5410.540104091039, 4928.211486162197, 5077.593792496517, 5422.024035673617, 5441.972790136024, 5454.918176227439], "value": 4000.0383610543827, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.6675195905965, 716.3445447415612, 750.2834792772902, 794.4002278279897, 808.3088958940104, 15584.454544090959, 16208.432704855317, 16213.432529861457, 16248.51254462365, 16274.186290685462, 11997.920187183152, 14110.858891411082, 14159.292035398215, 14219.644508887282, 14690.55928322152, 667.9899801502982, 687.6871023684212, 827.871679889615, 833.6207025803245, 860.1699360117485, 815.7185770909027, 823.8269963307682, 825.2531459029558, 825.6821123867325, 828.7679449754048, 907.6097278170384, 911.1571796088591, 911.2118017914522, 911.886014248219, 912.2838571721201, 700.1528150937321, 873.8077622922947, 903.0698380667923, 904.7150147703452, 914.2914241462853, 596.9850753731148, 658.6574981009848, 677.4275737002274, 677.5595862689242, 677.8305423644089, 14979.70040599182, 15016.399344026226, 15021.699566008729, 15067.17130557819, 15110.39558417662, 13844.446222151202, 14040.1575905446, 14144.929275353661, 14168.079074860205, 14362.497312594009, 12919.095663303573, 13108.737825243537, 13172.802407963889, 13341.733165336638, 13533.729325413437, 4486.125205584913, 4560.247559152735, 4674.766261686936, 4682.7658617069355, 4787.712737235742, 110546.0, 111148.0, 111377.44311278392, 111456.0, 111678.44160779225, 9903.158231550398, 10995.450227488594, 11043.944780276208, 11365.772684546262, 11431.14266430021, 22286.219982300543, 22288.55422891533, 22329.665055024187, 22381.55236895253, 22417.551648966928, 7632.80917977055, 7889.09275433321, 7956.880646790303, 8131.471454355448, 8164.6325915333955, 4321.611055005064, 4385.386045953569, 4400.515943246241, 4411.228035093845, 4577.519360467103, 3436.60479044908, 3577.5885773136424, 3578.7315951303235, 3584.426491761306, 3589.2462582857893, 626.7586979013022, 635.2630948100274, 638.5051585021553, 639.597053856077, 646.8188907106017, 588.3645662684377, 595.7587177193244, 603.7343568829701, 608.3247595169358, 645.2160624840841, 8227.382946278933, 8756.891303587263, 9101.317401194805, 9122.452652840851, 9287.674931377369, 529.1982646290872, 564.6504853400377, 590.2179612014098, 625.3996163682824, 630.5460068750492, 4262.577977010987, 4389.495208051051, 4423.093265880487, 4442.311441726538, 4481.395011673382, 97248.0, 142920.85618715727, 157643.0, 162529.3747062538, 166827.1658641699, 133352.0, 133522.0, 133821.0, 135664.32167839294, 138804.30597846946, 97519.2480751919, 119445.40277298732, 120992.0, 123363.0, 124275.37862310812, 8538.44500107491, 8544.487330759926, 8559.914400855912, 8568.0, 8696.735857709808, 52099.47900520946, 64829.02756458562, 66939.0, 67527.24727527208, 69227.0, 83473.0, 91927.16145676916, 98187.01812981779, 103272.48363758133, 105620.94379056111, 13049.869501304865, 13156.73686526245, 13317.134386264861, 13497.257650828906, 13918.729565623686, 12408.379581021094, 14948.25258737037, 15064.6987060256, 15171.364199280788, 15470.14914179686, 11580.768384632429, 13775.242361669803, 13981.091229070078, 14139.151650900978, 14263.572092837232, 13733.313334333443, 13745.518906838213, 13782.241976690977, 14040.368183431974, 14090.859091408955, 13387.464501419834, 13502.598381565329, 13773.862261377259, 13777.586672399844, 14207.502737404142, 10281.2803103785, 10316.587336506456, 10386.896131038593, 10400.375977441377, 10604.098651614699, 11299.943500282774, 11328.433578321215, 11385.203035787747, 11608.6517404478, 11677.591284305348], "value": 7846.525342551019, "unit": "score"}], "name": "dromaeo_css", "value": 7437.531043073272, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 116.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 122.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 128.0, 161.0, 109.0, 127.0, 127.0, 108.0, 158.0, 108.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 183.0, 186.0, 185.0, 185.0, 181.0, 181.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 80.0, 152.0, 59.0, 61.0, 60.0, 69.0, 156.0, 58.0], "value": 84.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 132.0, 71.0, 69.0, 79.0, 121.0, 90.0, 97.0, 144.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 90.0, 87.0, 88.0, 85.0, 89.0, 87.0, 88.0, 86.0], "value": 87.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 93.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 84.0, 84.0, 83.0, 84.0, 83.0, 84.0, 84.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 55.0, 53.0, 55.0, 57.0, 56.0, 54.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 73.0, 66.0, 70.0, 76.0, 73.0, 69.0, 70.0, 72.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 85.0, 85.0, 87.0, 88.0, 84.0, 86.0, 90.0, 90.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 139.0, 137.0, 142.0, 140.0, 139.0, 138.0, 182.0, 139.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 240.0, 190.0, 177.0, 179.0, 178.0, 177.0, 182.0, 176.0, 175.0], "value": 184.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 69.0, 66.0, 70.0, 70.0, 69.0, 70.0, 68.0, 70.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1485.6, "alertThreshold": 2.0}]} 09:58:01 INFO - Return code: 0 09:58:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:01 INFO - # TBPL SUCCESS # 09:58:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:01 INFO - 2016-09-01 09:58:01.321 screenresolution[1667:10527] starting screenresolution argv=screenresolution get 09:58:01 INFO - 2016-09-01 09:58:01.323 screenresolution[1667:10527] Display 0: 1600x1200x32@60 09:58:01 INFO - 2016-09-01 09:58:01.329 screenresolution[1668:10530] starting screenresolution argv=screenresolution list 09:58:01 INFO - Available Modes on Display 0 09:58:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:01 INFO - Intel Iris: 09:58:01 INFO - Chipset Model: Intel Iris 09:58:01 INFO - Type: GPU 09:58:01 INFO - Bus: Built-In 09:58:01 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:01 INFO - Vendor: Intel (0x8086) 09:58:01 INFO - Device ID: 0x0a2e 09:58:01 INFO - Revision ID: 0x0009 09:58:01 INFO - Displays: 09:58:01 INFO - Display: 09:58:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:01 INFO - Display Serial Number: 1600x1200 60 09:58:01 INFO - Main Display: Yes 09:58:01 INFO - Mirror: Off 09:58:01 INFO - Online: Yes 09:58:01 INFO - Rotation: Supported 09:58:01 INFO - Return code: 0 09:58:01 INFO - Running post-action listener: _resource_record_post_action 09:58:01 INFO - [mozharness: 2016-09-01 16:58:01.574275Z] Finished run-tests step (success) 09:58:01 INFO - Running post-run listener: _resource_record_post_run 09:58:01 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31857664; Write bytes: 705316352; Read time: 196; Write time: 1936 09:58:01 INFO - TinderboxPrint: CPU usage
53.7% 09:58:01 INFO - TinderboxPrint: I/O read bytes / time
31,857,664 / 196 09:58:01 INFO - TinderboxPrint: I/O write bytes / time
705,316,352 / 1,936 09:58:01 INFO - TinderboxPrint: CPU idle
2,247.6 (46.3%) 09:58:01 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 09:58:01 INFO - TinderboxPrint: CPU user
1,632.1 (33.7%) 09:58:01 INFO - TinderboxPrint: Swap in / out
434,417,664 / 0 09:58:01 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398866944; Write bytes: 393684480; Read time: 29713; Write time: 783 09:58:01 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29809664; Write bytes: 294920192; Read time: 178; Write time: 1109 09:58:02 INFO - Running post-run listener: _upload_blobber_files 09:58:02 INFO - Blob upload gear active. 09:58:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:02 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 09:58:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:58:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:58:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:58:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:03 INFO - (blobuploader) - INFO - Done attempting. 09:58:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:58:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:58:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:04 INFO - (blobuploader) - INFO - Done attempting. 09:58:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:07 INFO - (blobuploader) - INFO - Done attempting. 09:58:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:07 INFO - Return code: 0 09:58:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a070692d03f5bb230d5b0397d3a4808c7eb09ee78f955bcbafcc1fd5985ea90bf3edd24e3233cac9a0d4310de42d9589ec523bf32b4102078c8cba7861928610", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ba9156f400e4ef0623c74224c2cca5e3fb9b0423e50e5dffe352ab0daa0fb65d663159c74ff455a3b73f5b8675216c13caadd009c8f34a69e9ddb35e02ed269", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d88f2988cf32e55a27dd473ed29b53f337cc9c862ff1787e0bc557ef824cb9361c82269821c6e2fc0d12e7bfbf1bcc6890ed7842656456f92b9bf87b4e5928f"} 09:58:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:07 INFO - Contents: 09:58:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a070692d03f5bb230d5b0397d3a4808c7eb09ee78f955bcbafcc1fd5985ea90bf3edd24e3233cac9a0d4310de42d9589ec523bf32b4102078c8cba7861928610", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ba9156f400e4ef0623c74224c2cca5e3fb9b0423e50e5dffe352ab0daa0fb65d663159c74ff455a3b73f5b8675216c13caadd009c8f34a69e9ddb35e02ed269", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d88f2988cf32e55a27dd473ed29b53f337cc9c862ff1787e0bc557ef824cb9361c82269821c6e2fc0d12e7bfbf1bcc6890ed7842656456f92b9bf87b4e5928f"} 09:58:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:07 INFO - Copying logs to upload dir... 09:58:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.079267 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 09:58:07.441747) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 09:58:07.445090) ========= 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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a070692d03f5bb230d5b0397d3a4808c7eb09ee78f955bcbafcc1fd5985ea90bf3edd24e3233cac9a0d4310de42d9589ec523bf32b4102078c8cba7861928610", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ba9156f400e4ef0623c74224c2cca5e3fb9b0423e50e5dffe352ab0daa0fb65d663159c74ff455a3b73f5b8675216c13caadd009c8f34a69e9ddb35e02ed269", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d88f2988cf32e55a27dd473ed29b53f337cc9c862ff1787e0bc557ef824cb9361c82269821c6e2fc0d12e7bfbf1bcc6890ed7842656456f92b9bf87b4e5928f"} build_url:https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011360 build_url: 'https://queue.taskcluster.net/v1/task/M2y6vID6TjuE7wsxggzyfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a070692d03f5bb230d5b0397d3a4808c7eb09ee78f955bcbafcc1fd5985ea90bf3edd24e3233cac9a0d4310de42d9589ec523bf32b4102078c8cba7861928610", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ba9156f400e4ef0623c74224c2cca5e3fb9b0423e50e5dffe352ab0daa0fb65d663159c74ff455a3b73f5b8675216c13caadd009c8f34a69e9ddb35e02ed269", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d88f2988cf32e55a27dd473ed29b53f337cc9c862ff1787e0bc557ef824cb9361c82269821c6e2fc0d12e7bfbf1bcc6890ed7842656456f92b9bf87b4e5928f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 09:58:07.478118) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:58:07.478441) ========= 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.PCHeZcNNRL/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.1SdGtPsMG8/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.005207 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:58:07.527678) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 09:58:07.528018) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 09:58:07.528405) ========= ========= Total master_lag: 0.17 =========