builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0251 starttime: 1471624321.09 results: success (0) buildid: 20160819065624 builduid: e5f5dd6625b64d668baf2d95ba41ba82 revision: 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:01.088368) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:01.088827) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:01.089105) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:01.215239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:01.215613) ========= 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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-19 09:32:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.3M=0.001s 2016-08-19 09:32:01 (17.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097553 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:01.335965) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:01.336272) ========= 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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.030602 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:01.390970) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-19 09:32:01.391312) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 --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 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TgWcXTIucT/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.t7RGkQ7c4S/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-19 09:32:01,479 truncating revision to first 12 chars 2016-08-19 09:32:01,479 Setting DEBUG logging. 2016-08-19 09:32:01,479 attempt 1/10 2016-08-19 09:32:01,479 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8dfc2fdb7ae3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 09:32:02,185 unpacking tar archive at: fx-team-8dfc2fdb7ae3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980425 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-19 09:32:02.400702) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:02.401098) ========= script_repo_revision: 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:02.401543) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:02.401829) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 09:32:02.420779) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-19 09:32:02.421157) ========= /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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:02 INFO - MultiFileLogger online at 20160819 09:32:02 in /builds/slave/test 09:32:02 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:32:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:32:02 INFO - {'append_to_log': False, 09:32:02 INFO - 'base_work_dir': '/builds/slave/test', 09:32:02 INFO - 'blob_upload_branch': 'Fx-Team', 09:32:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:32:02 INFO - 'branch': 'Fx-Team', 09:32:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:32:02 INFO - 'config_files': ('talos/mac_config.py',), 09:32:02 INFO - 'default_actions': ('clobber', 09:32:02 INFO - 'read-buildbot-config', 09:32:02 INFO - 'download-and-extract', 09:32:02 INFO - 'populate-webroot', 09:32:02 INFO - 'create-virtualenv', 09:32:02 INFO - 'install', 09:32:02 INFO - 'run-tests'), 09:32:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:32:02 INFO - 'download_minidump_stackwalk': True, 09:32:02 INFO - 'download_symbols': 'ondemand', 09:32:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:32:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:32:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:32:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:32:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:32:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:32:02 INFO - 'installer_path': 'installer.exe', 09:32:02 INFO - 'log_level': 'info', 09:32:02 INFO - 'log_name': 'talos', 09:32:02 INFO - 'log_to_console': True, 09:32:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:32:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:32:02 INFO - 'opt_config_files': (), 09:32:02 INFO - 'pip_index': False, 09:32:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:02 INFO - 'cmd': ('bash', 09:32:02 INFO - '-c', 09:32:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:02 INFO - 'enabled': True, 09:32:02 INFO - 'halt_on_failure': False, 09:32:02 INFO - 'name': 'check_screen_resolution'},), 09:32:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:32:02 INFO - 'cmd': ('bash', 09:32:02 INFO - '-c', 09:32:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:32:02 INFO - 'enabled': True, 09:32:02 INFO - 'halt_on_failure': False, 09:32:02 INFO - 'name': 'check_screen_resolution'},), 09:32:02 INFO - 'run_cmd_checks_enabled': True, 09:32:02 INFO - 'sps_profile': False, 09:32:02 INFO - 'sps_profile_interval': 0, 09:32:02 INFO - 'suite': 'dromaeojs', 09:32:02 INFO - 'system_bits': '32', 09:32:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:32:02 INFO - 'title': 't-yosemite-r7-0251', 09:32:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:32:02 INFO - 'use_talos_json': True, 09:32:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:32:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:32:02 INFO - 'work_dir': 'build'} 09:32:02 INFO - [mozharness: 2016-08-19 16:32:02.618942Z] Running clobber step. 09:32:02 INFO - Running pre-action listener: _resource_record_pre_action 09:32:02 INFO - Running main action method: clobber 09:32:02 INFO - rmtree: /builds/slave/test/build 09:32:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:32:04 INFO - Running post-action listener: _resource_record_post_action 09:32:04 INFO - [mozharness: 2016-08-19 16:32:04.635086Z] Finished clobber step (success) 09:32:04 INFO - [mozharness: 2016-08-19 16:32:04.635291Z] Running read-buildbot-config step. 09:32:04 INFO - Running pre-action listener: _resource_record_pre_action 09:32:04 INFO - Running main action method: read_buildbot_config 09:32:04 INFO - Using buildbot properties: 09:32:04 INFO - { 09:32:04 INFO - "project": "", 09:32:04 INFO - "product": "firefox", 09:32:04 INFO - "script_repo_revision": "production", 09:32:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:32:04 INFO - "repository": "", 09:32:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:32:04 INFO - "buildid": "20160819065624", 09:32:04 INFO - "pgo_build": "False", 09:32:04 INFO - "basedir": "/builds/slave/test", 09:32:04 INFO - "buildnumber": 93, 09:32:04 INFO - "slavename": "t-yosemite-r7-0251", 09:32:04 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:32:04 INFO - "platform": "yosemite_r7", 09:32:04 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:32:04 INFO - "branch": "fx-team", 09:32:04 INFO - "repo_path": "integration/fx-team", 09:32:04 INFO - "revision": "8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5", 09:32:04 INFO - "stage_platform": "macosx64", 09:32:04 INFO - "builduid": "e5f5dd6625b64d668baf2d95ba41ba82", 09:32:04 INFO - "slavebuilddir": "test" 09:32:04 INFO - } 09:32:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:32:04 INFO - Running post-action listener: _resource_record_post_action 09:32:04 INFO - [mozharness: 2016-08-19 16:32:04.638645Z] Finished read-buildbot-config step (success) 09:32:04 INFO - [mozharness: 2016-08-19 16:32:04.638753Z] Running download-and-extract step. 09:32:04 INFO - Running pre-action listener: _resource_record_pre_action 09:32:04 INFO - Running main action method: download_and_extract 09:32:04 INFO - mkdir: /builds/slave/test/build/tests 09:32: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:32:04 INFO - trying https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:32:04 INFO - Downloading https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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:32:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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:32:06 INFO - Downloaded 1199 bytes. 09:32:06 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:32:06 INFO - Using the following test package requirements: 09:32:06 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:32:06 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:32:06 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:32:06 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:32:06 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:32:06 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:32:06 INFO - u'jsshell-mac.zip'], 09:32:06 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:32:06 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:32:06 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:32:06 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:32:06 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:32:06 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:32:06 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:32:06 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:32:06 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:32:06 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:32:06 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:32:06 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:32:06 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:32:06 INFO - trying https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:32:06 INFO - Downloading https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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:32:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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:32:07 INFO - Downloaded 16350149 bytes. 09:32:07 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:32:14 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:32: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:32:14 INFO - trying https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:32:14 INFO - Downloading https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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:32:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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:32:15 INFO - Downloaded 16350149 bytes. 09:32:15 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:32: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:32:21 INFO - trying https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:32:21 INFO - Downloading https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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:32:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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:32:22 INFO - Downloaded 14413981 bytes. 09:32:22 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:32: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:32:22 INFO - trying https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:32:22 INFO - Downloading https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:32:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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:32:25 INFO - Downloaded 111356283 bytes. 09:32:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:32:25 INFO - mkdir: /builds/slave/test/properties 09:32:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:32:25 INFO - Writing to file /builds/slave/test/properties/build_url 09:32:25 INFO - Contents: 09:32:25 INFO - build_url:https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:32:26 INFO - Running post-action listener: _resource_record_post_action 09:32:26 INFO - Running post-action listener: set_extra_try_arguments 09:32:26 INFO - [mozharness: 2016-08-19 16:32:26.361135Z] Finished download-and-extract step (success) 09:32:26 INFO - [mozharness: 2016-08-19 16:32:26.361293Z] Running populate-webroot step. 09:32:26 INFO - Running pre-action listener: _resource_record_pre_action 09:32:26 INFO - Running main action method: populate_webroot 09:32:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:32:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:32:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:32:26 INFO - '--tppagecycles', 09:32:26 INFO - '7'], 09:32:26 INFO - 'tests': ['tsvgm']}}, 09:32:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:32:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:32:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:32:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:32:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:26 INFO - 'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:32:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:32:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:26 INFO - 'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['damp', 'tps']}, 09:32:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:26 INFO - 'tests': ['damp', 'tps']}, 09:32:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['dromaeo_dom']}, 09:32:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:32:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['basic_compositor_video']}, 09:32:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:32:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['a11yr', 09:32:26 INFO - 'ts_paint', 09:32:26 INFO - 'tpaint', 09:32:26 INFO - 'sessionrestore', 09:32:26 INFO - 'sessionrestore_no_auto_restore', 09:32:26 INFO - 'tabpaint']}, 09:32:26 INFO - 'other-e10s': {'tests': ['a11yr', 09:32:26 INFO - 'ts_paint', 09:32:26 INFO - 'tpaint', 09:32:26 INFO - 'sessionrestore', 09:32:26 INFO - 'sessionrestore_no_auto_restore', 09:32:26 INFO - 'tabpaint']}, 09:32:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:32:26 INFO - 'ts_paint', 09:32:26 INFO - 'tpaint', 09:32:26 INFO - 'sessionrestore', 09:32:26 INFO - 'sessionrestore_no_auto_restore', 09:32:26 INFO - 'tabpaint']}, 09:32:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:32:26 INFO - 'ts_paint', 09:32:26 INFO - 'tpaint', 09:32:26 INFO - 'sessionrestore', 09:32:26 INFO - 'sessionrestore_no_auto_restore', 09:32:26 INFO - 'tabpaint']}, 09:32:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['a11yr', 09:32:26 INFO - 'ts_paint', 09:32:26 INFO - 'tpaint', 09:32:26 INFO - 'sessionrestore', 09:32:26 INFO - 'sessionrestore_no_auto_restore', 09:32:26 INFO - 'tabpaint']}, 09:32:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['a11yr', 09:32:26 INFO - 'ts_paint', 09:32:26 INFO - 'tpaint', 09:32:26 INFO - 'sessionrestore', 09:32:26 INFO - 'sessionrestore_no_auto_restore', 09:32:26 INFO - 'tabpaint']}, 09:32:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['tsvgx', 09:32:26 INFO - 'tsvgr_opacity', 09:32:26 INFO - 'tart', 09:32:26 INFO - 'tscrollx', 09:32:26 INFO - 'cart']}, 09:32:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:32:26 INFO - 'tsvgr_opacity', 09:32:26 INFO - 'tart', 09:32:26 INFO - 'tscrollx', 09:32:26 INFO - 'cart']}, 09:32:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:26 INFO - 'talos_options': ['--disable-e10s'], 09:32:26 INFO - 'tests': ['tp5o']}, 09:32:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:26 INFO - 'tests': ['tp5o']}, 09:32:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:26 INFO - 'talos_options': ['--disable-e10s', 09:32:26 INFO - '--xperf_path', 09:32:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:32:26 INFO - 'tests': ['tp5n']}, 09:32:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:32:26 INFO - 'talos_options': ['--xperf_path', 09:32:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:32:26 INFO - 'tests': ['tp5n']}}, 09:32:26 INFO - 'talos.zip': {'path': '', 09:32:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:32:26 INFO - Running post-action listener: _resource_record_post_action 09:32:26 INFO - [mozharness: 2016-08-19 16:32:26.380725Z] Finished populate-webroot step (success) 09:32:26 INFO - [mozharness: 2016-08-19 16:32:26.380858Z] Running create-virtualenv step. 09:32:26 INFO - Running pre-action listener: _resource_record_pre_action 09:32:26 INFO - Running main action method: create_virtualenv 09:32:26 INFO - Creating virtualenv /builds/slave/test/build/venv 09:32:26 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:32:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:32:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:32:26 INFO - Using real prefix '/tools/python27' 09:32:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:32:27 INFO - Installing distribute.............................................................................................................................................................................................done. 09:32:30 INFO - Installing pip.................done. 09:32:30 INFO - Return code: 0 09:32:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:32:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:30 INFO - 'HOME': '/Users/cltbld', 09:32:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:30 INFO - 'LOGNAME': 'cltbld', 09:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:30 INFO - 'MOZ_NO_REMOTE': '1', 09:32:30 INFO - 'NO_EM_RESTART': '1', 09:32:30 INFO - 'PAGER': '/bin/cat', 09:32:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:30 INFO - 'PWD': '/builds/slave/test', 09:32:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:30 INFO - 'SHELL': '/bin/bash', 09:32:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:30 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:30 INFO - 'USER': 'cltbld', 09:32:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:30 INFO - 'XPC_FLAGS': '0x0', 09:32:30 INFO - 'XPC_SERVICE_NAME': '0', 09:32:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:30 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:32:30 INFO - Cleaning up... 09:32:30 INFO - Return code: 0 09:32:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:32:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:30 INFO - 'HOME': '/Users/cltbld', 09:32:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:30 INFO - 'LOGNAME': 'cltbld', 09:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:30 INFO - 'MOZ_NO_REMOTE': '1', 09:32:30 INFO - 'NO_EM_RESTART': '1', 09:32:30 INFO - 'PAGER': '/bin/cat', 09:32:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:30 INFO - 'PWD': '/builds/slave/test', 09:32:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:30 INFO - 'SHELL': '/bin/bash', 09:32:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:30 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:30 INFO - 'USER': 'cltbld', 09:32:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:30 INFO - 'XPC_FLAGS': '0x0', 09:32:30 INFO - 'XPC_SERVICE_NAME': '0', 09:32:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:30 INFO - Downloading/unpacking psutil>=3.1.1 09:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:32:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:32:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:32:33 INFO - Installing collected packages: psutil 09:32:33 INFO - Running setup.py install for psutil 09:32:33 INFO - building 'psutil._psutil_osx' extension 09:32:33 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:32:34 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:32:34 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:32:34 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:32:34 INFO - building 'psutil._psutil_posix' extension 09:32:34 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:32:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:32:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:32:34 INFO - ^ 09:32:34 INFO - 1 warning generated. 09:32:34 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:32:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:32:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:32:34 INFO - Successfully installed psutil 09:32:34 INFO - Cleaning up... 09:32:34 INFO - Return code: 0 09:32:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:32:34 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:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:34 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:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:34 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 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:34 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:32:34 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:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:34 INFO - 'HOME': '/Users/cltbld', 09:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:34 INFO - 'LOGNAME': 'cltbld', 09:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:34 INFO - 'MOZ_NO_REMOTE': '1', 09:32:34 INFO - 'NO_EM_RESTART': '1', 09:32:34 INFO - 'PAGER': '/bin/cat', 09:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:34 INFO - 'PWD': '/builds/slave/test', 09:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:34 INFO - 'SHELL': '/bin/bash', 09:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:34 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:34 INFO - 'USER': 'cltbld', 09:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:34 INFO - 'XPC_FLAGS': '0x0', 09:32:34 INFO - 'XPC_SERVICE_NAME': '0', 09:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:32: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:32: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:32: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:32: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:32: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:32: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:32:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:32:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:32:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:32:37 INFO - Installing collected packages: mozsystemmonitor 09:32:37 INFO - Running setup.py install for mozsystemmonitor 09:32:37 INFO - Successfully installed mozsystemmonitor 09:32:37 INFO - Cleaning up... 09:32:37 INFO - Return code: 0 09:32:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:32: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:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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:32:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:37 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 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:37 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:32:37 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:32:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:37 INFO - 'HOME': '/Users/cltbld', 09:32:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:37 INFO - 'LOGNAME': 'cltbld', 09:32:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:37 INFO - 'MOZ_NO_REMOTE': '1', 09:32:37 INFO - 'NO_EM_RESTART': '1', 09:32:37 INFO - 'PAGER': '/bin/cat', 09:32:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:37 INFO - 'PWD': '/builds/slave/test', 09:32:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:37 INFO - 'SHELL': '/bin/bash', 09:32:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:37 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:37 INFO - 'USER': 'cltbld', 09:32:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:37 INFO - 'XPC_FLAGS': '0x0', 09:32:37 INFO - 'XPC_SERVICE_NAME': '0', 09:32:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:38 INFO - Downloading/unpacking jsonschema==2.5.1 09:32:38 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:32:38 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:32:38 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:32:38 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:32:38 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:32:38 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:32:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:32:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:32:40 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:32:40 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:32:40 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:32:40 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:32:40 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:32:40 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:32:40 INFO - Downloading functools32-3.2.3-2.tar.gz 09:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:32:40 INFO - warning: no files found matching '*.txt' 09:32:40 INFO - no previously-included directories found matching 'build' 09:32:40 INFO - no previously-included directories found matching 'dist' 09:32:40 INFO - no previously-included directories found matching '.git*' 09:32:40 INFO - Installing collected packages: jsonschema, functools32 09:32:40 INFO - Running setup.py install for functools32 09:32:41 INFO - warning: no files found matching '*.txt' 09:32:41 INFO - no previously-included directories found matching 'build' 09:32:41 INFO - no previously-included directories found matching 'dist' 09:32:41 INFO - no previously-included directories found matching '.git*' 09:32:41 INFO - Successfully installed jsonschema functools32 09:32:41 INFO - Cleaning up... 09:32:41 INFO - Return code: 0 09:32:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:41 INFO - 'HOME': '/Users/cltbld', 09:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:41 INFO - 'LOGNAME': 'cltbld', 09:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:41 INFO - 'MOZ_NO_REMOTE': '1', 09:32:41 INFO - 'NO_EM_RESTART': '1', 09:32:41 INFO - 'PAGER': '/bin/cat', 09:32:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:41 INFO - 'PWD': '/builds/slave/test', 09:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:41 INFO - 'SHELL': '/bin/bash', 09:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:41 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:41 INFO - 'USER': 'cltbld', 09:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:41 INFO - 'XPC_FLAGS': '0x0', 09:32:41 INFO - 'XPC_SERVICE_NAME': '0', 09:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:32:41 INFO - Cleaning up... 09:32:41 INFO - Return code: 0 09:32:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:41 INFO - 'HOME': '/Users/cltbld', 09:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:41 INFO - 'LOGNAME': 'cltbld', 09:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:41 INFO - 'MOZ_NO_REMOTE': '1', 09:32:41 INFO - 'NO_EM_RESTART': '1', 09:32:41 INFO - 'PAGER': '/bin/cat', 09:32:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:41 INFO - 'PWD': '/builds/slave/test', 09:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:41 INFO - 'SHELL': '/bin/bash', 09:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:41 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:41 INFO - 'USER': 'cltbld', 09:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:41 INFO - 'XPC_FLAGS': '0x0', 09:32:41 INFO - 'XPC_SERVICE_NAME': '0', 09:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:41 INFO - Downloading/unpacking blobuploader==1.2.4 09:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:45 INFO - Downloading blobuploader-1.2.4.tar.gz 09:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:32:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:32:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:32:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:32:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:32:46 INFO - Downloading docopt-0.6.1.tar.gz 09:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:32:46 INFO - Installing collected packages: blobuploader, requests, docopt 09:32:46 INFO - Running setup.py install for blobuploader 09:32:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:32:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:32:46 INFO - Running setup.py install for requests 09:32:47 INFO - Running setup.py install for docopt 09:32:47 INFO - Successfully installed blobuploader requests docopt 09:32:47 INFO - Cleaning up... 09:32:47 INFO - Return code: 0 09:32:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32: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:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32: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:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:47 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 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:47 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:32:47 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:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:47 INFO - 'HOME': '/Users/cltbld', 09:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:47 INFO - 'LOGNAME': 'cltbld', 09:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:47 INFO - 'MOZ_NO_REMOTE': '1', 09:32:47 INFO - 'NO_EM_RESTART': '1', 09:32:47 INFO - 'PAGER': '/bin/cat', 09:32:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:47 INFO - 'PWD': '/builds/slave/test', 09:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:47 INFO - 'SHELL': '/bin/bash', 09:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:47 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:47 INFO - 'USER': 'cltbld', 09:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:47 INFO - 'XPC_FLAGS': '0x0', 09:32:47 INFO - 'XPC_SERVICE_NAME': '0', 09:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:47 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-c7a9ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:47 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-wsQrPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:47 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-gLz6MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:47 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-YXEQUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:47 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-HYoQ_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:48 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-v8s80a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:48 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-0A3tsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:48 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ZMBQae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:48 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-1Dksy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:48 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-8xh03t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:48 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-V93upD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:48 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-dVCUpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:48 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-SYYI68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:48 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-VVVYs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:49 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-jDMdTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:49 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-lmgUOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:49 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-X125zr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:32:49 INFO - Running setup.py install for manifestparser 09:32:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:32:49 INFO - Running setup.py install for mozcrash 09:32:49 INFO - Running setup.py install for mozdebug 09:32:49 INFO - Running setup.py install for mozdevice 09:32:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:32:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:32:49 INFO - Running setup.py install for mozfile 09:32:50 INFO - Running setup.py install for mozhttpd 09:32:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:32:50 INFO - Running setup.py install for mozinfo 09:32:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:32:50 INFO - Running setup.py install for mozInstall 09:32:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:32:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:32:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:32:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:32:50 INFO - Running setup.py install for mozleak 09:32:50 INFO - Running setup.py install for mozlog 09:32:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:32:50 INFO - Running setup.py install for moznetwork 09:32:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:32:50 INFO - Running setup.py install for mozprocess 09:32:51 INFO - Running setup.py install for mozprofile 09:32:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:32:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:32:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:32:51 INFO - Running setup.py install for mozrunner 09:32:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:32:51 INFO - Running setup.py install for mozscreenshot 09:32:51 INFO - Running setup.py install for moztest 09:32:51 INFO - Running setup.py install for mozversion 09:32:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:32:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:32:51 INFO - Cleaning up... 09:32:51 INFO - Return code: 0 09:32:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:51 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:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:52 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:32:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:52 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 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:52 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:32:52 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:32:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:52 INFO - 'HOME': '/Users/cltbld', 09:32:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:52 INFO - 'LOGNAME': 'cltbld', 09:32:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:52 INFO - 'MOZ_NO_REMOTE': '1', 09:32:52 INFO - 'NO_EM_RESTART': '1', 09:32:52 INFO - 'PAGER': '/bin/cat', 09:32:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:52 INFO - 'PWD': '/builds/slave/test', 09:32:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:52 INFO - 'SHELL': '/bin/bash', 09:32:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:52 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:52 INFO - 'USER': 'cltbld', 09:32:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:52 INFO - 'XPC_FLAGS': '0x0', 09:32:52 INFO - 'XPC_SERVICE_NAME': '0', 09:32:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-PAykX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:52 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:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-orWiWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:52 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:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-EspB3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:52 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:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:52 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-czyMsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-XBKXsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Pl09vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-qw4qQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-dk9g9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-FbGBmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-ew7ew9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-3Ol1sQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-mG2LvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:53 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-QJ5Cxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:53 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:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:54 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-Np2Lks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:54 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:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:54 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-lHBgtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:54 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:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:54 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-zizMM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:54 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:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:54 INFO - Running setup.py (path:/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/pip-gnjW5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:54 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:32:54 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:32:54 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:32:54 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:32:54 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:32:54 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:32:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:54 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:32:54 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:32:54 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:32:54 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:32:54 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:32:54 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:32:57 INFO - Downloading blessings-1.6.tar.gz 09:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:32:57 INFO - Installing collected packages: blessings 09:32:57 INFO - Running setup.py install for blessings 09:32:57 INFO - Successfully installed blessings 09:32:57 INFO - Cleaning up... 09:32:57 INFO - Return code: 0 09:32:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:57 INFO - Reading from file tmpfile_stdout 09:32:57 INFO - Current package versions: 09:32:57 INFO - blessings == 1.6 09:32:57 INFO - blobuploader == 1.2.4 09:32:57 INFO - docopt == 0.6.1 09:32:57 INFO - functools32 == 3.2.3-2 09:32:57 INFO - jsonschema == 2.5.1 09:32:57 INFO - manifestparser == 1.1 09:32:57 INFO - mozInstall == 1.12 09:32:57 INFO - mozcrash == 0.17 09:32:57 INFO - mozdebug == 0.1 09:32:57 INFO - mozdevice == 0.48 09:32:57 INFO - mozfile == 1.2 09:32:57 INFO - mozhttpd == 0.7 09:32:57 INFO - mozinfo == 0.9 09:32:57 INFO - mozleak == 0.1 09:32:57 INFO - mozlog == 3.3 09:32:57 INFO - moznetwork == 0.27 09:32:57 INFO - mozprocess == 0.23 09:32:57 INFO - mozprofile == 0.28 09:32:57 INFO - mozrunner == 6.12 09:32:57 INFO - mozscreenshot == 0.1 09:32:57 INFO - mozsystemmonitor == 0.3 09:32:57 INFO - moztest == 0.7 09:32:57 INFO - mozversion == 1.4 09:32:57 INFO - psutil == 3.1.1 09:32:57 INFO - requests == 1.2.3 09:32:57 INFO - wsgiref == 0.1.2 09:32:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:57 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:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:57 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:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:57 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 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:57 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:32:57 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:32:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:57 INFO - 'HOME': '/Users/cltbld', 09:32:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:57 INFO - 'LOGNAME': 'cltbld', 09:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:57 INFO - 'MOZ_NO_REMOTE': '1', 09:32:57 INFO - 'NO_EM_RESTART': '1', 09:32:57 INFO - 'PAGER': '/bin/cat', 09:32:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:57 INFO - 'PWD': '/builds/slave/test', 09:32:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:57 INFO - 'SHELL': '/bin/bash', 09:32:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:57 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:57 INFO - 'USER': 'cltbld', 09:32:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:57 INFO - 'XPC_FLAGS': '0x0', 09:32:57 INFO - 'XPC_SERVICE_NAME': '0', 09:32:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:58 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:32:58 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:32:58 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:32:58 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:32:58 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:32:58 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:32:58 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:32:58 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:32:58 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:32:58 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:32:58 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:32:58 INFO - Cleaning up... 09:32:58 INFO - Return code: 0 09:32:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:32:58 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 0x1102dca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110371618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda494d9e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, '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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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:32:58 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:32:58 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:32:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:32:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:32:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:32:58 INFO - 'HOME': '/Users/cltbld', 09:32:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:32:58 INFO - 'LOGNAME': 'cltbld', 09:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:58 INFO - 'MOZ_NO_REMOTE': '1', 09:32:58 INFO - 'NO_EM_RESTART': '1', 09:32:58 INFO - 'PAGER': '/bin/cat', 09:32:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:58 INFO - 'PWD': '/builds/slave/test', 09:32:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:32:58 INFO - 'SHELL': '/bin/bash', 09:32:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:32:58 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:32:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:32:58 INFO - 'USER': 'cltbld', 09:32:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:32:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:58 INFO - 'XPC_FLAGS': '0x0', 09:32:58 INFO - 'XPC_SERVICE_NAME': '0', 09:32:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:32:58 INFO - Cleaning up... 09:32:58 INFO - Return code: 0 09:32:58 INFO - Running post-action listener: _resource_record_post_action 09:32:58 INFO - Running post-action listener: _start_resource_monitoring 09:32:58 INFO - Starting resource monitoring. 09:32:58 INFO - [mozharness: 2016-08-19 16:32:58.538257Z] Finished create-virtualenv step (success) 09:32:58 INFO - [mozharness: 2016-08-19 16:32:58.539049Z] Running install step. 09:32:58 INFO - Running pre-action listener: _resource_record_pre_action 09:32:58 INFO - Running main action method: install 09:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:58 INFO - Reading from file tmpfile_stdout 09:32:58 INFO - Detecting whether we're running mozinstall >=1.0... 09:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:32:58 INFO - Reading from file tmpfile_stdout 09:32:58 INFO - Output received: 09:32:58 INFO - Usage: mozinstall [options] installer 09:32:58 INFO - Options: 09:32:58 INFO - -h, --help show this help message and exit 09:32:58 INFO - -d DEST, --destination=DEST 09:32:58 INFO - Directory to install application into. [default: 09:32:58 INFO - "/builds/slave/test"] 09:32:58 INFO - --app=APP Application being installed. [default: firefox] 09:32:58 INFO - mkdir: /builds/slave/test/build/application 09:32:58 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:32:58 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:33:33 INFO - Reading from file tmpfile_stdout 09:33:33 INFO - Output received: 09:33:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:33:33 INFO - Running post-action listener: _resource_record_post_action 09:33:33 INFO - [mozharness: 2016-08-19 16:33:33.467035Z] Finished install step (success) 09:33:33 INFO - [mozharness: 2016-08-19 16:33:33.467200Z] Running run-tests step. 09:33:33 INFO - Running pre-action listener: _resource_record_pre_action 09:33:33 INFO - Running main action method: run_tests 09:33:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:33:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:33:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:33:33 INFO - 2016-08-19 09:33:33.481 screenresolution[1635:7554] starting screenresolution argv=screenresolution get 09:33:33 INFO - 2016-08-19 09:33:33.484 screenresolution[1635:7554] Display 0: 1600x1200x32@60 09:33:33 INFO - 2016-08-19 09:33:33.490 screenresolution[1636:7556] starting screenresolution argv=screenresolution list 09:33:33 INFO - Available Modes on Display 0 09:33:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:33:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:33:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:33:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:33:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:33:33 INFO - Intel Iris: 09:33:33 INFO - Chipset Model: Intel Iris 09:33:33 INFO - Type: GPU 09:33:33 INFO - Bus: Built-In 09:33:33 INFO - VRAM (Dynamic, Max): 1536 MB 09:33:33 INFO - Vendor: Intel (0x8086) 09:33:33 INFO - Device ID: 0x0a2e 09:33:33 INFO - Revision ID: 0x0009 09:33:33 INFO - Displays: 09:33:33 INFO - Display: 09:33:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:33:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:33:33 INFO - Display Serial Number: 1600x1200 60 09:33:33 INFO - Main Display: Yes 09:33:33 INFO - Mirror: Off 09:33:33 INFO - Online: Yes 09:33:33 INFO - Rotation: Supported 09:33:33 INFO - Return code: 0 09:33:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:33:33 INFO - Python 2.7.3 09:33:33 INFO - Return code: 0 09:33:33 INFO - grabbing minidump binary from tooltool 09:33:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:33 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 0x110240d30>, '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 0x10ffa5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110238b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:33:33 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:33:33 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:33:33 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:33:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:33:33 INFO - Return code: 0 09:33:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:33:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:33:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:33:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:33:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:33:33 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/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--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:33:33 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/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0251 --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:33:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TgWcXTIucT/Render', 09:33:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:33:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:33:33 INFO - 'HOME': '/Users/cltbld', 09:33:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:33:33 INFO - 'LOGNAME': 'cltbld', 09:33:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:33:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:33 INFO - 'MOZ_NO_REMOTE': '1', 09:33:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:33:33 INFO - 'NO_EM_RESTART': '1', 09:33:33 INFO - 'PAGER': '/bin/cat', 09:33:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:33 INFO - 'PWD': '/builds/slave/test', 09:33:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:33:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:33:33 INFO - 'SHELL': '/bin/bash', 09:33:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t7RGkQ7c4S/Listeners', 09:33:33 INFO - 'TMPDIR': '/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/', 09:33:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:33:33 INFO - 'USER': 'cltbld', 09:33:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:33:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:33 INFO - 'XPC_FLAGS': '0x0', 09:33:33 INFO - 'XPC_SERVICE_NAME': '0', 09:33:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:33:33 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/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0251', '--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:33:34 INFO - mozversion application_buildid: 20160819065624 09:33:34 INFO - mozversion application_changeset: 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 09:33:34 INFO - mozversion application_display_name: Nightly 09:33:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:33:34 INFO - mozversion application_name: Firefox 09:33:34 INFO - mozversion application_remotingname: firefox 09:33:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:33:34 INFO - mozversion application_vendor: Mozilla 09:33:34 INFO - mozversion application_version: 51.0a1 09:33:34 INFO - mozversion platform_buildid: 20160819065624 09:33:34 INFO - mozversion platform_changeset: 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 09:33:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:33:34 INFO - mozversion platform_version: 51.0a1 09:33:34 INFO - using testdate: 1471624414 09:33:34 INFO - actual date: 1471624414 09:33:34 INFO - starting webserver on 'localhost:49262' 09:33:34 INFO - SUITE-START | Running 2 tests 09:33:34 INFO - TEST-START | dromaeo_css 09:33:34 INFO - operating with platform_type : mac_ 09:33:34 INFO - Initialising browser for dromaeo_css test... 09:33:34 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpWphty8/profile http://localhost:49262/getInfo.html) 09:33:36 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 09:33:36 INFO - PROCESS | 1646 | colorDepth:24 09:33:36 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 09:33:36 INFO - PROCESS | 1646 | __metrics 09:33:36 INFO - PROCESS | 1646 | 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:33:36 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:33:37 INFO - TEST-INFO | 1646: exit 0 09:33:37 INFO - Browser initialized. 09:33:37 INFO - Running cycle 1/1 for dromaeo_css test... 09:33:37 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpWphty8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:49 INFO - PROCESS | 1648 | RSS: Main: 228397056 09:33:49 INFO - PROCESS | 1648 | 09:34:40 INFO - PROCESS | 1648 | 1471624480741 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:36:51 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49262/tests/dromaeo/cssquery-ext.html) 09:36:53 INFO - PROCESS | 1648 | RSS: Main: 319021056 09:36:53 INFO - PROCESS | 1648 | 09:39:55 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-ext.html (next: http://localhost:49262/tests/dromaeo/cssquery-jquery.html) 09:39:56 INFO - PROCESS | 1648 | RSS: Main: 367136768 09:39:56 INFO - PROCESS | 1648 | 09:42:58 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49262/tests/dromaeo/cssquery-mootools.html) 09:42:59 INFO - PROCESS | 1648 | RSS: Main: 359055360 09:42:59 INFO - PROCESS | 1648 | 09:46:02 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49262/tests/dromaeo/cssquery-prototype.html) 09:46:02 INFO - PROCESS | 1648 | RSS: Main: 355823616 09:46:02 INFO - PROCESS | 1648 | 09:49:06 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49262/tests/dromaeo/cssquery-yui.html) 09:49:06 INFO - PROCESS | 1648 | RSS: Main: 338083840 09:49:06 INFO - PROCESS | 1648 | 09:52:10 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49262/tests/dromaeo/cssquery-yui.html (next: http://localhost:49262/tests/dromaeo/cssquery-dojo.html) 09:52:10 INFO - PROCESS | 1648 | RSS: Main: 379248640 09:52:10 INFO - PROCESS | 1648 | 09:52:10 INFO - PROCESS | 1648 | __start_tp_report 09:52:10 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 09:52:10 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 09:52:10 INFO - PROCESS | 1648 | |i|pagename|runs| 09:52:10 INFO - PROCESS | 1648 | |0;dojo.html;2836.7164432631735;3373.61203461602;3374.595048594166;3376.9324613507724;3404.6935775780175;5546.362168350631;5652;5697.487226149646;5761.596688231825;5809.883802323956;4861.513848615136;5377.381601115872;5739.770409183638;5791.276090488689;5805.12923061541;5136.8458946231485;5170.172772356424;5182.948170518284;5201.895962080756;5205.6616319939085;5262.9473705263035;5274.287971123907;5281.683099014072;5282.973585132069;5306.097963346221;5140.460251673595;5178.404483484373;5275.894482110336;5313.814016509423;5315.202719592073;5141.151709967889;5212.270282160501;5227.634065615409;5228.163493840967;5254.500822421864;5245.86885327866;5264.815731449381;5265.631405801595;5268.394134674523;5273.525382715536;5756.395578464285;5762.538996880239;5778.619507588066;5795.985702502046;5796.942030579682;6003.8198854034445;6200.596961197508;6307;6311.747530098791;6323.336049714806;6264.3422440643535;6319.178506794087;6336.58812177207;6349.619022858643;6352.46004089653;5827.242458480413;5831.708414579254;5864.618799778001;5909.763609455574;5914.467697907209;6219.844503887349;6233.781817636361;6300.842478938063;6313.747450101944;6314.684265786784;6123.418275263843;6137.877242455215;6146.139540464339;6157.107219453154;6160.075988601746;6246.812595622138;6256.343083976208;6260.280067792171;6276.372362763778;6293.30773614902;6003.969980150158;6098.817035488943;6211.472024877936;6213.03697926822;6215.96892015537;6222.62664240138;6253.843653908689;6306.9684651576445;6309.684515774193;6327.810165695037;4988.55103040728;4989.401271847401;4994.450610432849;4995.375578052744;5002.824901128443;4371.431713877239;4462;4510.391097201836;4533.048059907456;4559.703619264804;5686.3744988051285;5695.686737229492;5747.626404283708;5754.309482862;5796.0436527972415;6337.588056776295;6344.6827658616885;6353.491720662336;6374.776882809079;6379.266384365857;5746.597738158289;5768;5797.826065218051;5807.825765226966;5816.47651711348;5312.415634280226;5361.249425080492;5410.48600382963;5435.782568697208;5483.232347471358;6282.371762823773;6296.622202667854;6304.621722696653;6309.148264984168;6324.462420694293;6335.746570137143;6354.046892966092;6364.363563643598;6369.649669268235;6381.329960354096;6270.655113968775;6271.247450305992;6322.36776322364;6333.050042493663;6336.904946425715;6237;6263.467605253604;6264.091706702411;6269.749210031548;6286.308506064421;6261.499080073492;6266.811995640138;6326.43062124411;6343.9682801585695;6369.49044076482;5923.348431672426;5999.100134979788;6026.578139530191;6080.665563394055;6176.227971503562;6011.699415029144;6092.45167934888;6142.6314421134875;6154.78458253959;6157.26112866441;4571.862844114682;5357.571394288369;5980.611260268069;5994;5996.790112346222;5938.703064846654;5998.70006499682;6065.090236464389;6097.63414195132;6158.291796443466;5930.34766175729;5934.495567876779;5938.465538101592;5949.3455719871645;5959.731812068554 09:52:10 INFO - PROCESS | 1648 | |1;ext.html;469.2093821910084;477.0935223076155;504.99242511362337;518.3468829275113;524.7218973943809;29740.810367585298;39100.41349379758;39799.801000994994;39831;39839.00402489939;41725;42052.158956820844;42055.78972105143;42098;42234;9572.521373931311;9767.316287859852;9769;9772.169365603897;9782.51087445625;9625.470599117045;9729.173020293249;9744.464054477;9747.512624368788;9748.463834489101;9724.854127188099;9780.315377923549;9785.559649815776;9796.412215267106;9803.411795292306;9676.564554595025;9765;9783.315167938212;9783.41299522031;9788.168005719452;9384.577694003718;9454.05459454056;9457.527123643791;9458.668946586837;9480.051994800533;39491.60508394937;39815.8009209955;39819.60180398188;39908.60091399078;39930.800345998374;3317.170707323169;3403.7787543809823;3411.5564976553137;3414.931701365959;3528.523649307336;24793.62809557858;30032;31039.896010399007;31060.844695776603;31183;3147.9685203147906;3246.902592922216;3279.180204948763;3297.884574039921;3326.4677651575876;504224;504450;505279.4208086845;505988;526043;54344;54370.72814635901;54611.08500234919;54669.453305466435;54926;57591.42408575944;57615.711921440125;57680.423195768344;57770;57813.710931445916;15789.605259868365;15995.920020399824;16029.43896963624;16068.276927538289;16095.758563621555;12590.37048147589;12649.367531623382;12652.810207846705;12656.36718164088;12673.873261267454;14182.290885455686;14746.852531474762;14769.48306809278;14780.852191478163;14982.400703971936;4850.611951043908;5173.379194496684;5751.913721294184;5857.970710146507;5874.853128671817;848.7708318753902;933.710549729586;959.2949182350967;1008.5411137932223;1071.9624813131502;29253.7074629251;29306.267343316587;29541.704582953895;29685;29686.257843554085;808.5593351623379;998.0767789794556;1008.2438171371472;1042.9739256518496;1051.1433181956681;14225;14747.852521474862;15091.471798487;15279.236038198045;15404;661623;678864;678910;679537;680974;56831.715841421355;57109.42890571124;57290.42709572934;57331;57650;1224.161449407159;1270.3203785974645;1326.907116501855;1361.203695837926;1368.705728268427;29305;30514.177072808387;31327;31566;32004.839975799972;50645.49354506408;51260.23109653433;51282.23076653779;51286.743566281926;51458;617735;622727;623863;624113;624675;37055.888323350344;37985.86042418732;38622.80688596539;38628.420573690855;38671.41992870053;45541.772291139656;45578.86052848705;46564.53435465602;46655;47308;38087.80956095312;38148;38789;38807.91929180973;39186.412203817534;37504.062398440255;37543.62456375401;37885.43171852479;38963.41554876622;39200;30972.845135774176;31494.842525787226;37128;37916.43125353067;37979.43030854595;31607.525887111253;32944.83527582347;33821.492677610346;33833.64665413356;34024.65975340215;24804.627930580697;25136.77022602046;25339.366515837253;25884.188106832342;25971.74028259769 09:52:10 INFO - PROCESS | 1648 | |2;jquery.html;369.2651623269694;371.2648955068963;380.853371451991;389.92396482685854;401.1115379434552;15094.47169349073;15893.920530397361;19150.616987660254;22044.779552204494;22210.66683999741;20590;20752.481187970272;21294;21297.467563310885;21519.031643576036;4993.325901003354;6050.092486127083;6160.691965401735;6185.195924529828;6309.495240380758;6188.412100850412;6220.657863817488;6250.031245156985;6255.749770009194;6274.905876411857;4075.510938687347;5724.22722932405;6240.594361366497;6843.144606924135;6901.240863505011;6631.568948018412;6853.965730171367;6910.308969103075;6937.9653101734675;6949.409300209463;5986.670733109677;5993.101034844764;6001.039833626599;6003.6697981611;6007.729652165663;26552.672366381605;26734.86632566844;27015.459690806074;27121.728782712315;27513;2151.204054499845;2229.3869185973986;2264.060414927803;2384.594618914771;2821.7601503872197;15099.245037748069;15465.922670386688;15536.766948495782;16074.75887861683;16542.66914661712;1847.0856925821804;1930.2954421636143;1946.2896042944365;1950.2978927586057;1975.8221760041522;120622;125539.3723031388;126070;126900;129770;10721.731956700989;10993.285436446606;11050.171237157245;11183.03780842485;11445.25605835618;13782.862171378358;13783.379747911367;13785.862141378659;13801.378937947817;13822.447102116004;8402.201790829862;8644.438111522732;8687.826243475221;8816.735497935073;8828.470291782514;7219.8917016244795;8756.255718263892;8756.605952732143;8785.209331160098;8988.730338089868;5507.807226747124;6626.337366263431;6719.563228390237;6734.696938637675;6738.730450782012;1558.306553583667;1677.270387381493;1800.7304008285082;1838.1544489534697;1842.5117343903876;767.6313792729691;826.8718348655968;884.1423818895661;906.1708536688988;1008.3596915958419;21112.472188195417;22862.31413057611;22873.428164296027;22883.656745148837;22905.19831805912;901.6078006067381;935.2471260635118;943.6461327002374;953.3279038278135;1003.3227571389283;11410;11702.590409335633;11862.169648124722;12036.277823330629;12043.819342709867;493678;496460;499323;623085;644781;94366.5281673601;117360;123614.02052784328;134288;144407.27796360952;1016.3343010328247;1020.5815615597718;1044.3420644993605;1099.0987390339844;1103.0403548912336;18267.721259511705;18819.435416937515;19544.511387214865;19908.800911991275;20199;52737.47262527326;55095.72452137714;56206.94137527642;56321.15518267147;56408;75754;80291;81249.59375203087;81954.18045819465;83455;8004.399670024655;8068.9596552016865;8165.79585510367;8263.586820659064;8325.25072743456;10694.144468442662;12917.483300667867;13166.605001849959;13353;13519.932400337935;11002.734685511268;11138.721531961766;11152;11505.769884602429;11549.538018479167;9453.320606299925;10949.343039417661;11127;11184.608538701106;11631.185816993067;9532.666356677482;11195.944020280172;11713.941430292794;11755.471003804678;11779.998700110244;7997;8103.392245581487;8191.918080819116;8274;8277.710280140167;9671.613135474503;9741.366811157252;10899.836502452628;11506.424678766196;11577.942110289394 09:52:10 INFO - PROCESS | 1648 | |3;mootools.html;1331.860154683758;1479.445208046982;1618.5063555615532;1805.7652505174326;1841.1254654039324;8378.706745263917;8935.972363178236;8942.821143577125;8948.36023373105;8959.328050396221;7837.568933708644;8080.232377924102;8503.95748021259;8682.522461264627;8750.86873696895;2344.800691941187;2368.100121953655;2370.9407264818346;2372.50177462733;2376.4296568823443;2347.448349637842;2359.858408495496;2391.5575618510543;2394.4133687246563;2395.197608801045;2355.34050465869;2373.1219448803968;2380.1669415704537;2394.676718642987;2399.992003358585;2334.4047267946735;2345.94135146621;2351.6472529120597;2357.587422201108;2368.348704106367;2336.707911511061;2349.4361353275262;2357.1514254868234;2358.693369861924;2360.197532838843;8749.868751968726;8832.470051796849;8860;8879.023307436177;8897.510636914967;5176.50823171798;5347;5350.8127215547265;5352.652077614943;5355.3305836770405;3563.322968635951;3583.229605634775;3636.254567813592;3639.144800971796;3650.981745091284;1092.8524649172339;1097.5829184909762;1098.6319582939645;1115.1524841120724;1127.689885281546;11499.712507187387;11592.826107608393;11597.826032609517;11604.477798499087;11613.593524226697;4248.617624413818;4274.893127671771;4626.398568186114;4764.237721964469;4802.135615589227;8322.916770832215;9826.705198844045;9851.704448866545;9853.113219810251;9855.704328870146;5298.099323115002;5300.787968481218;5302.2046692996355;5308.575313974873;5322.2282768998275;4380.890477738082;4530.750808705487;4720.457147428021;4832.589229915427;4851.636127290397;3612.952243849307;3677.4667673187237;3877.6122387760997;3955.129871428281;3983.8406463741703;1220.4568966809684;1261.7350356425254;1277.9872201278054;1290.851552071505;1298.7467443848548;826.7850358906727;868.405142477405;889.8932128144664;932.3939439364467;957.1720461800493;5086.669366491166;5836.649801011868;6087.634741915411;6101.389861013952;6130;921.8755468011733;929.5259417696888;939.8778158839307;947.9052094790466;980.6371642492181;3865.6103373614606;3979.2837289287627;4025.75845449274;4067.186562687474;4143.461350024478;10536.051755342056;10777.83833242502;10859.13126949842;10867.619633312795;10876;10125.848112278321;10665.626703065356;10693.037626613643;10815.242932994772;10866;8026.71906483282;8243.010838699394;8246.422750407533;8267.255946964682;8284.130166332448;5940.1683764272175;5956.987312156857;6088.665123418255;6128.356522565066;6174.536909731698;9620.807583848424;10032.297739158188;10094;10109.292349535463;10109.595616175271;9613.279004074582;10213.33613315132;10390.324628899098;10403.063724264852;10403.803562590096;6933.445324373937;7190.101237345332;7353.8161545961775;7483.139438964588;7635.923640763522;8197.83604327922;8546.2308392245;8548.786280342792;8553.95723021381;8565.400421970402;6440.677966101583;7382.667779950022;7453.850922981618;7501.249875012455;7541.132769731332;7424.886267059768;7452.3665488431925;7480.850382992201;7489.588072656056;7518.774436766906;6886.311368863074;7045.859082818418;7048.506604537838;7049.823754406181;7058.082449281561;4201.495820501559;4214.4310518079665;4993.625478089085;5017.071841709149;5089.541941225308;4069.450624165818;4964.726940018189;5092.388913330424;5160.277561141518;5166 09:52:10 INFO - PROCESS | 1648 | |4;prototype.html;294.1484402654315;300.36922462828056;310.80574640849477;312.19609505523283;314.1909582824228;4728.895641439561;5166.121759300919;5173.741312934357;5188.558972487343;5188.6367954243215;3739.4951681522984;3934.5081864766903;5012.170557746424;5059.621977670489;5195;3268.4607039838397;3554.7689400188906;3711.8634753633137;3928.292907276689;3999.9000524724606;3946.9407958880497;3947.664448521879;3969.8809035728973;3984.740991835536;3989.301872172373;3649.6532829381167;3680.392735198689;3795.0891785971307;3901.4928059352246;3937.27160475313;2931.9120426387244;3926.5680775114715;3929.4891664083484;3988.6011398860173;4000.919981600366;3120.2823350629255;3189.0751682012187;3409.3010932758725;3433.364827506919;3442.862285508577;3923.8430462781453;4857.130004948085;5051.393832740095;5056.317397151374;5091.338126043628;3751.568569614502;4230.8519201827785;4509.774511274423;4521.909561808779;4539.345826609578;4929.260610908356;4945.9258111128;5208.5312321891215;5344.973275133639;5438.374586922515;3432.0390290718638;3642.471841582982;3804.524434445694;3824.1969186470874;3826.0243637872163;4834.758262086882;5323.52088312046;5619.690916999522;5637.661740295595;5718.34239062509;3423.9828800856335;3609.819509024538;3802.4106263529197;3812.84748610058;3816;3492.1967947372245;3895.9610403896163;3913.9217215655526;3917;3947.289487892207;3781.7352785305316;4282.486101667758;4498.887527811831;4501.864944051684;4539.7049191802425;4425.690201685851;5313.884084342331;5362.812301569505;5414.7834086636885;5443.972780136153;1131.2081542919989;1295.585412667956;1327.482281910056;1328.7408955253634;1336.906416550832;819.610684924656;838.7441830241835;841.7264389073575;873.4977388001925;874.7813046738315;4551.3173024046;4833.927491087637;4904.485029071968;4913.034786085523;5042.697438153723;5454.972725136428;5503.871706300199;5509.47659972302;5516.475934786189;5543.307086614173;4569.766163135935;4670.462896766915;5004.874878128003;5012.273220383024;5015.470281564158;2349.1777877742993;2706.499297629945;2730.5358089124898;2865.226388874992;2894.9276268093467;4789.449213340441;5011.674241174312;5220.536441342673;5450.754716037788;5647.6893770843;4742.976285118552;5040.747962601855;5249.790008399697;5347.433232048638;5668.0647693130995;5287.947120528823;5665.631733937364;5694.320175548223;5710.914336284876;5715.028445164332;4607.40103786519;5447.237386765777;5484.314460692413;5502.339719233718;5565.805196818092;4481.843135490242;4650.511696271843;5165.302684137593;5447.836564902901;5496.450354964471;4713.6431784107945;5045.84862454127;5378.601563593415;5638.464345887134;5698.886022279448;3408.9432275994523;3774.414965680324;3825.7895815730403;3835.980820095882;3868.883933482;4488.730676159441;5062.5093390447955;5548.445155484581;5561.332640083216;5580.776768929198;4774.819321036714;4897.877553061202;5209.400918894224;5300.496452836975;5300.5759539236;4515.367848501278;4724.952750472588;4963.429205641253;5339.919901201408;5359.812406565751;4360.454943132108;5262.47401247411;5263.710495922761;5264.605154613496;5268.78924843002;4430.3575981483145;4563.315502674625;5020.297158397901;5212.843614691566;5342.278792362981;4066.1905452074725;5128.487151284841;5226.268322434787;5248.868778280574;5250.871062721533 09:52:10 INFO - PROCESS | 1648 | |5;yui.html;704.7216349541928;716.9677364518597;742.498813301022;780.1262585903785;816.6202715737181;15258.771118433218;16659;16670.58323541912;16682.165891705426;16694.7495787563;14961.625959351022;15082.245887705598;15103.320350584223;15110.395584176647;15118.924405377958;674.2212744280364;693.9479539034568;832.5046597274642;838.7777239031661;855.6278019061697;781.4803155901319;783.9137694853562;831.1356189562847;845.049319515545;846.9915300846974;897.5467388968594;898.3172788680585;899.3569597737617;900.4462255712755;901.9774505637346;701.4633805139073;867.3928250224868;892.5314210039717;894.6019021535419;895.1898531828706;656.6519744535403;657.9309172536863;657.9440747536418;659.4197106546209;659.4888961055173;12184;14921.701565968728;14929.178895160761;14929.477468288665;14931.850681493155;13906.930465347608;14077.788833167511;14121.435142594284;14248.430062797579;14535.854641453556;10757.031867131918;12350.567730129402;12446.128770986035;12677.492900283978;12871.970242380587;3917.5298964124486;4481.708688935209;4553.066621342617;4573.474050484204;4771.570558649738;98225;99076;99524.50237748837;100355.49822250842;106011.4699426498;10965.451727413598;11334.036606888507;11351.489182986785;11379.601713939972;11412.201145919871;22072.88963555172;22091.779082208974;22094.33716988493;22112.557748844934;22155.446113847283;6907.34380233877;8046.235607617267;8067.515949043076;8154.510729356258;8233.876491852627;4432.490263619725;4432.711873728197;4530.139273538083;4673.901633116215;4676.321933319649;3541.327147841902;3681.2637472505608;3691.151035261851;3693.8891833245048;3698.8890333290046;653.7450394346209;681.4684546054086;683.0164563029222;684.4250829303409;684.8835697931364;566.5580846939393;602.9457348838625;621.5773274173605;622.7664625765248;638.5274896576593;8298;8393.832123357499;8500.064992850781;8953.865692014626;9187;497.62926619668576;506.5770081981513;519.2315373247615;577.6573225978776;600.4685853020109;3513.402721537345;4312.870613881589;4334;4400.992526336129;4479.417675702138;95749.52125239329;126524.36737816436;144344;146389.89711131767;155262;124993;128878.99999999812;130179;131005.34497327643;133180;105161.94838051674;109851.35222971662;113264;113638;115066;8107.391945603986;8215.958920205361;8300.501969881827;8319.875201872097;8449.704260350858;60775.08837367532;62503.06245406232;62830.68584657047;63407;65025.841988983564;85067.49325067444;88213;96361.51819240859;96595.0340496586;98242.5087874556;9762.219022478326;11186.440677965906;11316.37759923212;11678.474468648761;11709.238899471507;12472;12500.749985000068;12590.748185036431;13010.349482526026;13037.934810325887;11696.298222106701;12092.697682558006;12190.634280971584;12271.877281227073;12367.257964522158;11694;11906.748123023659;12024.398780060787;12138.150329476852;12326.876731232931;11621.825672614748;11843.407829608657;12027.097967652637;12294.385280736105;12328.75342493163;9707.609888336545;9764.658236961672;9821.803563928539;9838.803223935623;10233.937036465948;7774.367382849665;9981.650642227778;10053.082971854234;10055.225487459593;10074.449864931656 09:52:10 INFO - PROCESS | 1648 | __end_tp_report 09:52:10 INFO - PROCESS | 1648 | __start_cc_report 09:52:10 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1352 09:52:10 INFO - PROCESS | 1648 | __end_cc_report 09:52:10 INFO - PROCESS | 1648 | __startTimestamp1471625530563__endTimestamp 09:52:10 INFO - PROCESS | 1648 | 09:52:10 INFO - PROCESS | 1648 | ------- Summary: start ------- 09:52:10 INFO - PROCESS | 1648 | Number of tests: 6 09:52:10 INFO - PROCESS | 1648 | 09:52:10 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5758.37 Median:5949.35 stddev:662.72 (11.1%) stddev-sans-first:623.86 09:52:10 INFO - PROCESS | 1648 | Values: 2836.7 3373.6 3374.6 3376.9 3404.7 5546.4 5652.0 5697.5 5761.6 5809.9 4861.5 5377.4 5739.8 5791.3 5805.1 5136.8 5170.2 5182.9 5201.9 5205.7 5262.9 5274.3 5281.7 5283.0 5306.1 5140.5 5178.4 5275.9 5313.8 5315.2 5141.2 5212.3 5227.6 5228.2 5254.5 5245.9 5264.8 5265.6 5268.4 5273.5 5756.4 5762.5 5778.6 5796.0 5796.9 6003.8 6200.6 6307.0 6311.7 6323.3 6264.3 6319.2 6336.6 6349.6 6352.5 5827.2 5831.7 5864.6 5909.8 5914.5 6219.8 6233.8 6300.8 6313.7 6314.7 6123.4 6137.9 6146.1 6157.1 6160.1 6246.8 6256.3 6260.3 6276.4 6293.3 6004.0 6098.8 6211.5 6213.0 6216.0 6222.6 6253.8 6307.0 6309.7 6327.8 4988.6 4989.4 4994.5 4995.4 5002.8 4371.4 4462.0 4510.4 4533.0 4559.7 5686.4 5695.7 5747.6 5754.3 5796.0 6337.6 6344.7 6353.5 6374.8 6379.3 5746.6 5768.0 5797.8 5807.8 5816.5 5312.4 5361.2 5410.5 5435.8 5483.2 6282.4 6296.6 6304.6 6309.1 6324.5 6335.7 6354.0 6364.4 6369.6 6381.3 6270.7 6271.2 6322.4 6333.1 6336.9 6237.0 6263.5 6264.1 6269.7 6286.3 6261.5 6266.8 6326.4 6344.0 6369.5 5923.3 5999.1 6026.6 6080.7 6176.2 6011.7 6092.5 6142.6 6154.8 6157.3 4571.9 5357.6 5980.6 5994.0 5996.8 5938.7 5998.7 6065.1 6097.6 6158.3 5930.3 5934.5 5938.5 5949.3 5959.7 09:52:10 INFO - PROCESS | 1648 | 09:52:10 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:72661.81 Median:39825.30 stddev:162434.85 (407.9%) stddev-sans-first:162800.09 09:52:10 INFO - PROCESS | 1648 | Values: 469.2 477.1 505.0 518.3 524.7 29740.8 39100.4 39799.8 39831.0 39839.0 41725.0 42052.2 42055.8 42098.0 42234.0 9572.5 9767.3 9769.0 9772.2 9782.5 9625.5 9729.2 9744.5 9747.5 9748.5 9724.9 9780.3 9785.6 9796.4 9803.4 9676.6 9765.0 9783.3 9783.4 9788.2 9384.6 9454.1 9457.5 9458.7 9480.1 39491.6 39815.8 39819.6 39908.6 39930.8 3317.2 3403.8 3411.6 3414.9 3528.5 24793.6 30032.0 31039.9 31060.8 31183.0 3148.0 3246.9 3279.2 3297.9 3326.5 504224.0 504450.0 505279.4 505988.0 526043.0 54344.0 54370.7 54611.1 54669.5 54926.0 57591.4 57615.7 57680.4 57770.0 57813.7 15789.6 15995.9 16029.4 16068.3 16095.8 12590.4 12649.4 12652.8 12656.4 12673.9 14182.3 14746.9 14769.5 14780.9 14982.4 4850.6 5173.4 5751.9 5858.0 5874.9 848.8 933.7 959.3 1008.5 1072.0 29253.7 29306.3 29541.7 29685.0 29686.3 808.6 998.1 1008.2 1043.0 1051.1 14225.0 14747.9 15091.5 15279.2 15404.0 661623.0 678864.0 678910.0 679537.0 680974.0 56831.7 57109.4 57290.4 57331.0 57650.0 1224.2 1270.3 1326.9 1361.2 1368.7 29305.0 30514.2 31327.0 31566.0 32004.8 50645.5 51260.2 51282.2 51286.7 51458.0 617735.0 622727.0 623863.0 624113.0 624675.0 37055.9 37985.9 38622.8 38628.4 38671.4 45541.8 45578.9 46564.5 46655.0 47308.0 38087.8 38148.0 38789.0 38807.9 39186.4 37504.1 37543.6 37885.4 38963.4 39200.0 30972.8 31494.8 37128.0 37916.4 37979.4 31607.5 32944.8 33821.5 33833.6 34024.7 24804.6 25136.8 25339.4 25884.2 25971.7 09:52:10 INFO - PROCESS | 1648 | 09:52:10 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:34284.12 Median:14468.63 stddev:93271.82 (644.6%) stddev-sans-first:93498.71 09:52:10 INFO - PROCESS | 1648 | Values: 369.3 371.3 380.9 389.9 401.1 15094.5 15893.9 19150.6 22044.8 22210.7 20590.0 20752.5 21294.0 21297.5 21519.0 4993.3 6050.1 6160.7 6185.2 6309.5 6188.4 6220.7 6250.0 6255.7 6274.9 4075.5 5724.2 6240.6 6843.1 6901.2 6631.6 6854.0 6910.3 6938.0 6949.4 5986.7 5993.1 6001.0 6003.7 6007.7 26552.7 26734.9 27015.5 27121.7 27513.0 2151.2 2229.4 2264.1 2384.6 2821.8 15099.2 15465.9 15536.8 16074.8 16542.7 1847.1 1930.3 1946.3 1950.3 1975.8 120622.0 125539.4 126070.0 126900.0 129770.0 10721.7 10993.3 11050.2 11183.0 11445.3 13782.9 13783.4 13785.9 13801.4 13822.4 8402.2 8644.4 8687.8 8816.7 8828.5 7219.9 8756.3 8756.6 8785.2 8988.7 5507.8 6626.3 6719.6 6734.7 6738.7 1558.3 1677.3 1800.7 1838.2 1842.5 767.6 826.9 884.1 906.2 1008.4 21112.5 22862.3 22873.4 22883.7 22905.2 901.6 935.2 943.6 953.3 1003.3 11410.0 11702.6 11862.2 12036.3 12043.8 493678.0 496460.0 499323.0 623085.0 644781.0 94366.5 117360.0 123614.0 134288.0 144407.3 1016.3 1020.6 1044.3 1099.1 1103.0 18267.7 18819.4 19544.5 19908.8 20199.0 52737.5 55095.7 56206.9 56321.2 56408.0 75754.0 80291.0 81249.6 81954.2 83455.0 8004.4 8069.0 8165.8 8263.6 8325.3 10694.1 12917.5 13166.6 13353.0 13519.9 11002.7 11138.7 11152.0 11505.8 11549.5 9453.3 10949.3 11127.0 11184.6 11631.2 9532.7 11195.9 11713.9 11755.5 11780.0 7997.0 8103.4 8191.9 8274.0 8277.7 9671.6 9741.4 10899.8 11506.4 11577.9 09:52:10 INFO - PROCESS | 1648 | 09:52:10 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5694.37 Median:4908.18 stddev:3191.88 (65.0%) stddev-sans-first:3183.99 09:52:10 INFO - PROCESS | 1648 | Values: 1331.9 1479.4 1618.5 1805.8 1841.1 8378.7 8936.0 8942.8 8948.4 8959.3 7837.6 8080.2 8504.0 8682.5 8750.9 2344.8 2368.1 2370.9 2372.5 2376.4 2347.4 2359.9 2391.6 2394.4 2395.2 2355.3 2373.1 2380.2 2394.7 2400.0 2334.4 2345.9 2351.6 2357.6 2368.3 2336.7 2349.4 2357.2 2358.7 2360.2 8749.9 8832.5 8860.0 8879.0 8897.5 5176.5 5347.0 5350.8 5352.7 5355.3 3563.3 3583.2 3636.3 3639.1 3651.0 1092.9 1097.6 1098.6 1115.2 1127.7 11499.7 11592.8 11597.8 11604.5 11613.6 4248.6 4274.9 4626.4 4764.2 4802.1 8322.9 9826.7 9851.7 9853.1 9855.7 5298.1 5300.8 5302.2 5308.6 5322.2 4380.9 4530.8 4720.5 4832.6 4851.6 3613.0 3677.5 3877.6 3955.1 3983.8 1220.5 1261.7 1278.0 1290.9 1298.7 826.8 868.4 889.9 932.4 957.2 5086.7 5836.6 6087.6 6101.4 6130.0 921.9 929.5 939.9 947.9 980.6 3865.6 3979.3 4025.8 4067.2 4143.5 10536.1 10777.8 10859.1 10867.6 10876.0 10125.8 10665.6 10693.0 10815.2 10866.0 8026.7 8243.0 8246.4 8267.3 8284.1 5940.2 5957.0 6088.7 6128.4 6174.5 9620.8 10032.3 10094.0 10109.3 10109.6 9613.3 10213.3 10390.3 10403.1 10403.8 6933.4 7190.1 7353.8 7483.1 7635.9 8197.8 8546.2 8548.8 8554.0 8565.4 6440.7 7382.7 7453.9 7501.2 7541.1 7424.9 7452.4 7480.9 7489.6 7518.8 6886.3 7045.9 7048.5 7049.8 7058.1 4201.5 4214.4 4993.6 5017.1 5089.5 4069.5 4964.7 5092.4 5160.3 5166.0 09:52:10 INFO - PROCESS | 1648 | 09:52:10 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4299.44 Median:4782.13 stddev:1306.03 (27.3%) stddev-sans-first:1274.63 09:52:10 INFO - PROCESS | 1648 | Values: 294.1 300.4 310.8 312.2 314.2 4728.9 5166.1 5173.7 5188.6 5188.6 3739.5 3934.5 5012.2 5059.6 5195.0 3268.5 3554.8 3711.9 3928.3 3999.9 3946.9 3947.7 3969.9 3984.7 3989.3 3649.7 3680.4 3795.1 3901.5 3937.3 2931.9 3926.6 3929.5 3988.6 4000.9 3120.3 3189.1 3409.3 3433.4 3442.9 3923.8 4857.1 5051.4 5056.3 5091.3 3751.6 4230.9 4509.8 4521.9 4539.3 4929.3 4945.9 5208.5 5345.0 5438.4 3432.0 3642.5 3804.5 3824.2 3826.0 4834.8 5323.5 5619.7 5637.7 5718.3 3424.0 3609.8 3802.4 3812.8 3816.0 3492.2 3896.0 3913.9 3917.0 3947.3 3781.7 4282.5 4498.9 4501.9 4539.7 4425.7 5313.9 5362.8 5414.8 5444.0 1131.2 1295.6 1327.5 1328.7 1336.9 819.6 838.7 841.7 873.5 874.8 4551.3 4833.9 4904.5 4913.0 5042.7 5455.0 5503.9 5509.5 5516.5 5543.3 4569.8 4670.5 5004.9 5012.3 5015.5 2349.2 2706.5 2730.5 2865.2 2894.9 4789.4 5011.7 5220.5 5450.8 5647.7 4743.0 5040.7 5249.8 5347.4 5668.1 5287.9 5665.6 5694.3 5710.9 5715.0 4607.4 5447.2 5484.3 5502.3 5565.8 4481.8 4650.5 5165.3 5447.8 5496.5 4713.6 5045.8 5378.6 5638.5 5698.9 3408.9 3774.4 3825.8 3836.0 3868.9 4488.7 5062.5 5548.4 5561.3 5580.8 4774.8 4897.9 5209.4 5300.5 5300.6 4515.4 4725.0 4963.4 5339.9 5359.8 4360.5 5262.5 5263.7 5264.6 5268.8 4430.4 4563.3 5020.3 5212.8 5342.3 4066.2 5128.5 5226.3 5248.9 5250.9 09:52:10 INFO - PROCESS | 1648 | 09:52:10 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:24015.27 Median:4456.06 stddev:38259.68 (858.6%) stddev-sans-first:38326.97 09:52:10 INFO - PROCESS | 1648 | Values: 704.7 717.0 742.5 780.1 816.6 15258.8 16659.0 16670.6 16682.2 16694.7 14961.6 15082.2 15103.3 15110.4 15118.9 674.2 693.9 832.5 838.8 855.6 781.5 783.9 831.1 845.0 847.0 897.5 898.3 899.4 900.4 902.0 701.5 867.4 892.5 894.6 895.2 656.7 657.9 657.9 659.4 659.5 12184.0 14921.7 14929.2 14929.5 14931.9 13906.9 14077.8 14121.4 14248.4 14535.9 10757.0 12350.6 12446.1 12677.5 12872.0 3917.5 4481.7 4553.1 4573.5 4771.6 98225.0 99076.0 99524.5 100355.5 106011.5 10965.5 11334.0 11351.5 11379.6 11412.2 22072.9 22091.8 22094.3 22112.6 22155.4 6907.3 8046.2 8067.5 8154.5 8233.9 4432.5 4432.7 4530.1 4673.9 4676.3 3541.3 3681.3 3691.2 3693.9 3698.9 653.7 681.5 683.0 684.4 684.9 566.6 602.9 621.6 622.8 638.5 8298.0 8393.8 8500.1 8953.9 9187.0 497.6 506.6 519.2 577.7 600.5 3513.4 4312.9 4334.0 4401.0 4479.4 95749.5 126524.4 144344.0 146389.9 155262.0 124993.0 128879.0 130179.0 131005.3 133180.0 105161.9 109851.4 113264.0 113638.0 115066.0 8107.4 8216.0 8300.5 8319.9 8449.7 60775.1 62503.1 62830.7 63407.0 65025.8 85067.5 88213.0 96361.5 96595.0 98242.5 9762.2 11186.4 11316.4 11678.5 11709.2 12472.0 12500.7 12590.7 13010.3 13037.9 11696.3 12092.7 12190.6 12271.9 12367.3 11694.0 11906.7 12024.4 12138.2 12326.9 11621.8 11843.4 12027.1 12294.4 12328.8 9707.6 9764.7 9821.8 9838.8 10233.9 7774.4 9981.7 10053.1 10055.2 10074.4 09:52:10 INFO - PROCESS | 1648 | -------- Summary: end -------- 09:52:10 INFO - PROCESS | 1648 | 09:52:11 INFO - TEST-INFO | 1648: exit 0 09:52:11 INFO - TEST-OK | dromaeo_css | took 1117090ms 09:52:11 INFO - TEST-START | kraken 09:52:11 INFO - operating with platform_type : mac_ 09:52:11 INFO - Initialising browser for kraken test... 09:52:11 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpXBi1TL/profile http://localhost:49262/getInfo.html) 09:52:13 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 09:52:13 INFO - PROCESS | 1658 | colorDepth:24 09:52:13 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 09:52:13 INFO - PROCESS | 1658 | __metrics 09:52:13 INFO - PROCESS | 1658 | 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:52:13 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:52:14 INFO - TEST-INFO | 1658: exit 0 09:52:14 INFO - Browser initialized. 09:52:14 INFO - Running cycle 1/1 for kraken test... 09:52:14 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/T/tmpXBi1TL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:26 INFO - PROCESS | 1659 | RSS: Main: 228257792 09:52:26 INFO - PROCESS | 1659 | 09:52:28 INFO - PROCESS | 1659 | 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:52:29 INFO - PROCESS | 1659 | RSS: Main: 292782080 09:52:29 INFO - PROCESS | 1659 | 09:52:32 INFO - PROCESS | 1659 | 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:52:33 INFO - PROCESS | 1659 | RSS: Main: 288141312 09:52:33 INFO - PROCESS | 1659 | 09:52:36 INFO - PROCESS | 1659 | 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:52:37 INFO - PROCESS | 1659 | RSS: Main: 380076032 09:52:37 INFO - PROCESS | 1659 | 09:52:39 INFO - PROCESS | 1659 | 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:52:39 INFO - PROCESS | 1659 | RSS: Main: 281382912 09:52:39 INFO - PROCESS | 1659 | 09:52:41 INFO - PROCESS | 1659 | 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:52:42 INFO - PROCESS | 1659 | RSS: Main: 285470720 09:52:42 INFO - PROCESS | 1659 | 09:52:45 INFO - PROCESS | 1659 | 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:52:46 INFO - PROCESS | 1659 | RSS: Main: 323756032 09:52:46 INFO - PROCESS | 1659 | 09:52:49 INFO - PROCESS | 1659 | 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:52:49 INFO - PROCESS | 1659 | RSS: Main: 324374528 09:52:49 INFO - PROCESS | 1659 | 09:52:52 INFO - PROCESS | 1659 | 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:52:53 INFO - PROCESS | 1659 | RSS: Main: 324280320 09:52:53 INFO - PROCESS | 1659 | 09:52:54 INFO - PROCESS | 1659 | 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:52:55 INFO - PROCESS | 1659 | RSS: Main: 302288896 09:52:55 INFO - PROCESS | 1659 | 09:52:57 INFO - PROCESS | 1659 | 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:52:57 INFO - PROCESS | 1659 | RSS: Main: 305111040 09:52:57 INFO - PROCESS | 1659 | 09:53:00 INFO - PROCESS | 1659 | 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:53:00 INFO - PROCESS | 1659 | RSS: Main: 304119808 09:53:00 INFO - PROCESS | 1659 | 09:53:03 INFO - PROCESS | 1659 | 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:53:04 INFO - PROCESS | 1659 | RSS: Main: 313434112 09:53:04 INFO - PROCESS | 1659 | 09:53:07 INFO - PROCESS | 1659 | 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:53:07 INFO - PROCESS | 1659 | RSS: Main: 314814464 09:53:07 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | 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:53:09 INFO - PROCESS | 1659 | RSS: Main: 358092800 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | __start_tp_report 09:53:09 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 09:53:09 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 09:53:09 INFO - PROCESS | 1659 | |i|pagename|runs| 09:53:09 INFO - PROCESS | 1659 | |0;ai-astar;102;114;113;113;113;111;112;113;112;113 09:53:09 INFO - PROCESS | 1659 | |1;audio-beat-detection;140;108;108;166;108;107;126;106;140;107 09:53:09 INFO - PROCESS | 1659 | |2;audio-dft;183;184;186;183;183;184;181;181;185;182 09:53:09 INFO - PROCESS | 1659 | |3;audio-fft;80;73;60;102;83;84;83;63;81;56 09:53:09 INFO - PROCESS | 1659 | |4;audio-oscillator;82;91;96;73;80;69;96;71;68;102 09:53:09 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;90;87;91;88;91;89;134;128;133;86 09:53:09 INFO - PROCESS | 1659 | |6;imaging-darkroom;95;94;95;95;94;95;96;95;94;101 09:53:09 INFO - PROCESS | 1659 | |7;imaging-desaturate;85;85;86;86;85;86;84;84;85;104 09:53:09 INFO - PROCESS | 1659 | |8;json-parse-financial;54;52;53;55;53;54;55;54;54;53 09:53:09 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;63;64;75;77;67;70;69;67;67;68 09:53:09 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;86;86;100;103;93;87;83;86;88;89 09:53:09 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;139;139;135;139;138;154;150;143;147;145 09:53:09 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;167;170;170;168;168;169;169;169;170;167 09:53:09 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;67;69;67;70;79;88;88;87;70 09:53:09 INFO - PROCESS | 1659 | __end_tp_report 09:53:09 INFO - PROCESS | 1659 | __start_cc_report 09:53:09 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2142 09:53:09 INFO - PROCESS | 1659 | __end_cc_report 09:53:09 INFO - PROCESS | 1659 | __startTimestamp1471625589977__endTimestamp 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | ------- Summary: start ------- 09:53:09 INFO - PROCESS | 1659 | Number of tests: 14 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:3.47 (3.1%) stddev-sans-first:0.87 09:53:09 INFO - PROCESS | 1659 | Values: 102.0 114.0 113.0 113.0 113.0 111.0 112.0 113.0 112.0 113.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:121.60 Median:117.00 stddev:20.80 (17.8%) stddev-sans-first:20.96 09:53:09 INFO - PROCESS | 1659 | Values: 140.0 108.0 108.0 166.0 108.0 107.0 126.0 106.0 140.0 107.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.62 (0.9%) stddev-sans-first:1.72 09:53:09 INFO - PROCESS | 1659 | Values: 183.0 184.0 186.0 183.0 183.0 184.0 181.0 181.0 185.0 182.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:76.50 Median:80.50 stddev:13.79 (17.1%) stddev-sans-first:14.56 09:53:09 INFO - PROCESS | 1659 | Values: 80.0 73.0 60.0 102.0 83.0 84.0 83.0 63.0 81.0 56.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:81.00 stddev:12.64 (15.6%) stddev-sans-first:13.40 09:53:09 INFO - PROCESS | 1659 | Values: 82.0 91.0 96.0 73.0 80.0 69.0 96.0 71.0 68.0 102.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:88.50 stddev:20.80 (23.5%) stddev-sans-first:21.62 09:53:09 INFO - PROCESS | 1659 | Values: 90.0 87.0 91.0 88.0 91.0 89.0 134.0 128.0 133.0 86.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.00 stddev:2.07 (2.2%) stddev-sans-first:2.19 09:53:09 INFO - PROCESS | 1659 | Values: 95.0 94.0 95.0 95.0 94.0 95.0 96.0 95.0 94.0 101.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:87.00 Median:85.00 stddev:6.02 (7.1%) stddev-sans-first:6.34 09:53:09 INFO - PROCESS | 1659 | Values: 85.0 85.0 86.0 86.0 85.0 86.0 84.0 84.0 85.0 104.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.95 (1.8%) stddev-sans-first:1.00 09:53:09 INFO - PROCESS | 1659 | Values: 54.0 52.0 53.0 55.0 53.0 54.0 55.0 54.0 54.0 53.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:68.50 stddev:4.40 (6.4%) stddev-sans-first:4.15 09:53:09 INFO - PROCESS | 1659 | Values: 63.0 64.0 75.0 77.0 67.0 70.0 69.0 67.0 67.0 68.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:86.50 stddev:6.57 (7.6%) stddev-sans-first:6.80 09:53:09 INFO - PROCESS | 1659 | Values: 86.0 86.0 100.0 103.0 93.0 87.0 83.0 86.0 88.0 89.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:144.00 stddev:6.03 (4.2%) stddev-sans-first:6.22 09:53:09 INFO - PROCESS | 1659 | Values: 139.0 139.0 135.0 139.0 138.0 154.0 150.0 143.0 147.0 145.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:169.00 stddev:1.16 (0.7%) stddev-sans-first:1.05 09:53:09 INFO - PROCESS | 1659 | Values: 167.0 170.0 170.0 168.0 168.0 169.0 169.0 169.0 170.0 167.0 09:53:09 INFO - PROCESS | 1659 | 09:53:09 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:74.50 stddev:9.28 (12.5%) stddev-sans-first:9.36 09:53:09 INFO - PROCESS | 1659 | Values: 67.0 67.0 69.0 67.0 70.0 79.0 88.0 88.0 87.0 70.0 09:53:09 INFO - PROCESS | 1659 | -------- Summary: end -------- 09:53:09 INFO - PROCESS | 1659 | 09:53:10 INFO - TEST-INFO | 1659: exit 0 09:53:10 INFO - TEST-OK | kraken | took 59699ms 09:53:10 INFO - SUITE-END | took 1176s 09:53:11 INFO - Completed test suite (00:19:37) 09:53:11 INFO - javascript benchmark 09:53:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2836.7164432631735, 3373.61203461602, 3374.595048594166, 3376.9324613507724, 3404.6935775780175, 5546.362168350631, 5652.0, 5697.487226149646, 5761.596688231825, 5809.883802323956, 4861.513848615136, 5377.381601115872, 5739.770409183638, 5791.276090488689, 5805.12923061541, 5136.8458946231485, 5170.172772356424, 5182.948170518284, 5201.895962080756, 5205.6616319939085, 5262.9473705263035, 5274.287971123907, 5281.683099014072, 5282.973585132069, 5306.097963346221, 5140.460251673595, 5178.404483484373, 5275.894482110336, 5313.814016509423, 5315.202719592073, 5141.151709967889, 5212.270282160501, 5227.634065615409, 5228.163493840967, 5254.500822421864, 5245.86885327866, 5264.815731449381, 5265.631405801595, 5268.394134674523, 5273.525382715536, 5756.395578464285, 5762.538996880239, 5778.619507588066, 5795.985702502046, 5796.942030579682, 6003.8198854034445, 6200.596961197508, 6307.0, 6311.747530098791, 6323.336049714806, 6264.3422440643535, 6319.178506794087, 6336.58812177207, 6349.619022858643, 6352.46004089653, 5827.242458480413, 5831.708414579254, 5864.618799778001, 5909.763609455574, 5914.467697907209, 6219.844503887349, 6233.781817636361, 6300.842478938063, 6313.747450101944, 6314.684265786784, 6123.418275263843, 6137.877242455215, 6146.139540464339, 6157.107219453154, 6160.075988601746, 6246.812595622138, 6256.343083976208, 6260.280067792171, 6276.372362763778, 6293.30773614902, 6003.969980150158, 6098.817035488943, 6211.472024877936, 6213.03697926822, 6215.96892015537, 6222.62664240138, 6253.843653908689, 6306.9684651576445, 6309.684515774193, 6327.810165695037, 4988.55103040728, 4989.401271847401, 4994.450610432849, 4995.375578052744, 5002.824901128443, 4371.431713877239, 4462.0, 4510.391097201836, 4533.048059907456, 4559.703619264804, 5686.3744988051285, 5695.686737229492, 5747.626404283708, 5754.309482862, 5796.0436527972415, 6337.588056776295, 6344.6827658616885, 6353.491720662336, 6374.776882809079, 6379.266384365857, 5746.597738158289, 5768.0, 5797.826065218051, 5807.825765226966, 5816.47651711348, 5312.415634280226, 5361.249425080492, 5410.48600382963, 5435.782568697208, 5483.232347471358, 6282.371762823773, 6296.622202667854, 6304.621722696653, 6309.148264984168, 6324.462420694293, 6335.746570137143, 6354.046892966092, 6364.363563643598, 6369.649669268235, 6381.329960354096, 6270.655113968775, 6271.247450305992, 6322.36776322364, 6333.050042493663, 6336.904946425715, 6237.0, 6263.467605253604, 6264.091706702411, 6269.749210031548, 6286.308506064421, 6261.499080073492, 6266.811995640138, 6326.43062124411, 6343.9682801585695, 6369.49044076482, 5923.348431672426, 5999.100134979788, 6026.578139530191, 6080.665563394055, 6176.227971503562, 6011.699415029144, 6092.45167934888, 6142.6314421134875, 6154.78458253959, 6157.26112866441, 4571.862844114682, 5357.571394288369, 5980.611260268069, 5994.0, 5996.790112346222, 5938.703064846654, 5998.70006499682, 6065.090236464389, 6097.63414195132, 6158.291796443466, 5930.34766175729, 5934.495567876779, 5938.465538101592, 5949.3455719871645, 5959.731812068554], "value": 5714.529031661888, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.2093821910084, 477.0935223076155, 504.99242511362337, 518.3468829275113, 524.7218973943809, 29740.810367585298, 39100.41349379758, 39799.801000994994, 39831.0, 39839.00402489939, 41725.0, 42052.158956820844, 42055.78972105143, 42098.0, 42234.0, 9572.521373931311, 9767.316287859852, 9769.0, 9772.169365603897, 9782.51087445625, 9625.470599117045, 9729.173020293249, 9744.464054477, 9747.512624368788, 9748.463834489101, 9724.854127188099, 9780.315377923549, 9785.559649815776, 9796.412215267106, 9803.411795292306, 9676.564554595025, 9765.0, 9783.315167938212, 9783.41299522031, 9788.168005719452, 9384.577694003718, 9454.05459454056, 9457.527123643791, 9458.668946586837, 9480.051994800533, 39491.60508394937, 39815.8009209955, 39819.60180398188, 39908.60091399078, 39930.800345998374, 3317.170707323169, 3403.7787543809823, 3411.5564976553137, 3414.931701365959, 3528.523649307336, 24793.62809557858, 30032.0, 31039.896010399007, 31060.844695776603, 31183.0, 3147.9685203147906, 3246.902592922216, 3279.180204948763, 3297.884574039921, 3326.4677651575876, 504224.0, 504450.0, 505279.4208086845, 505988.0, 526043.0, 54344.0, 54370.72814635901, 54611.08500234919, 54669.453305466435, 54926.0, 57591.42408575944, 57615.711921440125, 57680.423195768344, 57770.0, 57813.710931445916, 15789.605259868365, 15995.920020399824, 16029.43896963624, 16068.276927538289, 16095.758563621555, 12590.37048147589, 12649.367531623382, 12652.810207846705, 12656.36718164088, 12673.873261267454, 14182.290885455686, 14746.852531474762, 14769.48306809278, 14780.852191478163, 14982.400703971936, 4850.611951043908, 5173.379194496684, 5751.913721294184, 5857.970710146507, 5874.853128671817, 848.7708318753902, 933.710549729586, 959.2949182350967, 1008.5411137932223, 1071.9624813131502, 29253.7074629251, 29306.267343316587, 29541.704582953895, 29685.0, 29686.257843554085, 808.5593351623379, 998.0767789794556, 1008.2438171371472, 1042.9739256518496, 1051.1433181956681, 14225.0, 14747.852521474862, 15091.471798487, 15279.236038198045, 15404.0, 661623.0, 678864.0, 678910.0, 679537.0, 680974.0, 56831.715841421355, 57109.42890571124, 57290.42709572934, 57331.0, 57650.0, 1224.161449407159, 1270.3203785974645, 1326.907116501855, 1361.203695837926, 1368.705728268427, 29305.0, 30514.177072808387, 31327.0, 31566.0, 32004.839975799972, 50645.49354506408, 51260.23109653433, 51282.23076653779, 51286.743566281926, 51458.0, 617735.0, 622727.0, 623863.0, 624113.0, 624675.0, 37055.888323350344, 37985.86042418732, 38622.80688596539, 38628.420573690855, 38671.41992870053, 45541.772291139656, 45578.86052848705, 46564.53435465602, 46655.0, 47308.0, 38087.80956095312, 38148.0, 38789.0, 38807.91929180973, 39186.412203817534, 37504.062398440255, 37543.62456375401, 37885.43171852479, 38963.41554876622, 39200.0, 30972.845135774176, 31494.842525787226, 37128.0, 37916.43125353067, 37979.43030854595, 31607.525887111253, 32944.83527582347, 33821.492677610346, 33833.64665413356, 34024.65975340215, 24804.627930580697, 25136.77022602046, 25339.366515837253, 25884.188106832342, 25971.74028259769], "value": 19668.552839235497, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.2651623269694, 371.2648955068963, 380.853371451991, 389.92396482685854, 401.1115379434552, 15094.47169349073, 15893.920530397361, 19150.616987660254, 22044.779552204494, 22210.66683999741, 20590.0, 20752.481187970272, 21294.0, 21297.467563310885, 21519.031643576036, 4993.325901003354, 6050.092486127083, 6160.691965401735, 6185.195924529828, 6309.495240380758, 6188.412100850412, 6220.657863817488, 6250.031245156985, 6255.749770009194, 6274.905876411857, 4075.510938687347, 5724.22722932405, 6240.594361366497, 6843.144606924135, 6901.240863505011, 6631.568948018412, 6853.965730171367, 6910.308969103075, 6937.9653101734675, 6949.409300209463, 5986.670733109677, 5993.101034844764, 6001.039833626599, 6003.6697981611, 6007.729652165663, 26552.672366381605, 26734.86632566844, 27015.459690806074, 27121.728782712315, 27513.0, 2151.204054499845, 2229.3869185973986, 2264.060414927803, 2384.594618914771, 2821.7601503872197, 15099.245037748069, 15465.922670386688, 15536.766948495782, 16074.75887861683, 16542.66914661712, 1847.0856925821804, 1930.2954421636143, 1946.2896042944365, 1950.2978927586057, 1975.8221760041522, 120622.0, 125539.3723031388, 126070.0, 126900.0, 129770.0, 10721.731956700989, 10993.285436446606, 11050.171237157245, 11183.03780842485, 11445.25605835618, 13782.862171378358, 13783.379747911367, 13785.862141378659, 13801.378937947817, 13822.447102116004, 8402.201790829862, 8644.438111522732, 8687.826243475221, 8816.735497935073, 8828.470291782514, 7219.8917016244795, 8756.255718263892, 8756.605952732143, 8785.209331160098, 8988.730338089868, 5507.807226747124, 6626.337366263431, 6719.563228390237, 6734.696938637675, 6738.730450782012, 1558.306553583667, 1677.270387381493, 1800.7304008285082, 1838.1544489534697, 1842.5117343903876, 767.6313792729691, 826.8718348655968, 884.1423818895661, 906.1708536688988, 1008.3596915958419, 21112.472188195417, 22862.31413057611, 22873.428164296027, 22883.656745148837, 22905.19831805912, 901.6078006067381, 935.2471260635118, 943.6461327002374, 953.3279038278135, 1003.3227571389283, 11410.0, 11702.590409335633, 11862.169648124722, 12036.277823330629, 12043.819342709867, 493678.0, 496460.0, 499323.0, 623085.0, 644781.0, 94366.5281673601, 117360.0, 123614.02052784328, 134288.0, 144407.27796360952, 1016.3343010328247, 1020.5815615597718, 1044.3420644993605, 1099.0987390339844, 1103.0403548912336, 18267.721259511705, 18819.435416937515, 19544.511387214865, 19908.800911991275, 20199.0, 52737.47262527326, 55095.72452137714, 56206.94137527642, 56321.15518267147, 56408.0, 75754.0, 80291.0, 81249.59375203087, 81954.18045819465, 83455.0, 8004.399670024655, 8068.9596552016865, 8165.79585510367, 8263.586820659064, 8325.25072743456, 10694.144468442662, 12917.483300667867, 13166.605001849959, 13353.0, 13519.932400337935, 11002.734685511268, 11138.721531961766, 11152.0, 11505.769884602429, 11549.538018479167, 9453.320606299925, 10949.343039417661, 11127.0, 11184.608538701106, 11631.185816993067, 9532.666356677482, 11195.944020280172, 11713.941430292794, 11755.471003804678, 11779.998700110244, 7997.0, 8103.392245581487, 8191.918080819116, 8274.0, 8277.710280140167, 9671.613135474503, 9741.366811157252, 10899.836502452628, 11506.424678766196, 11577.942110289394], "value": 9914.703762315947, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.860154683758, 1479.445208046982, 1618.5063555615532, 1805.7652505174326, 1841.1254654039324, 8378.706745263917, 8935.972363178236, 8942.821143577125, 8948.36023373105, 8959.328050396221, 7837.568933708644, 8080.232377924102, 8503.95748021259, 8682.522461264627, 8750.86873696895, 2344.800691941187, 2368.100121953655, 2370.9407264818346, 2372.50177462733, 2376.4296568823443, 2347.448349637842, 2359.858408495496, 2391.5575618510543, 2394.4133687246563, 2395.197608801045, 2355.34050465869, 2373.1219448803968, 2380.1669415704537, 2394.676718642987, 2399.992003358585, 2334.4047267946735, 2345.94135146621, 2351.6472529120597, 2357.587422201108, 2368.348704106367, 2336.707911511061, 2349.4361353275262, 2357.1514254868234, 2358.693369861924, 2360.197532838843, 8749.868751968726, 8832.470051796849, 8860.0, 8879.023307436177, 8897.510636914967, 5176.50823171798, 5347.0, 5350.8127215547265, 5352.652077614943, 5355.3305836770405, 3563.322968635951, 3583.229605634775, 3636.254567813592, 3639.144800971796, 3650.981745091284, 1092.8524649172339, 1097.5829184909762, 1098.6319582939645, 1115.1524841120724, 1127.689885281546, 11499.712507187387, 11592.826107608393, 11597.826032609517, 11604.477798499087, 11613.593524226697, 4248.617624413818, 4274.893127671771, 4626.398568186114, 4764.237721964469, 4802.135615589227, 8322.916770832215, 9826.705198844045, 9851.704448866545, 9853.113219810251, 9855.704328870146, 5298.099323115002, 5300.787968481218, 5302.2046692996355, 5308.575313974873, 5322.2282768998275, 4380.890477738082, 4530.750808705487, 4720.457147428021, 4832.589229915427, 4851.636127290397, 3612.952243849307, 3677.4667673187237, 3877.6122387760997, 3955.129871428281, 3983.8406463741703, 1220.4568966809684, 1261.7350356425254, 1277.9872201278054, 1290.851552071505, 1298.7467443848548, 826.7850358906727, 868.405142477405, 889.8932128144664, 932.3939439364467, 957.1720461800493, 5086.669366491166, 5836.649801011868, 6087.634741915411, 6101.389861013952, 6130.0, 921.8755468011733, 929.5259417696888, 939.8778158839307, 947.9052094790466, 980.6371642492181, 3865.6103373614606, 3979.2837289287627, 4025.75845449274, 4067.186562687474, 4143.461350024478, 10536.051755342056, 10777.83833242502, 10859.13126949842, 10867.619633312795, 10876.0, 10125.848112278321, 10665.626703065356, 10693.037626613643, 10815.242932994772, 10866.0, 8026.71906483282, 8243.010838699394, 8246.422750407533, 8267.255946964682, 8284.130166332448, 5940.1683764272175, 5956.987312156857, 6088.665123418255, 6128.356522565066, 6174.536909731698, 9620.807583848424, 10032.297739158188, 10094.0, 10109.292349535463, 10109.595616175271, 9613.279004074582, 10213.33613315132, 10390.324628899098, 10403.063724264852, 10403.803562590096, 6933.445324373937, 7190.101237345332, 7353.8161545961775, 7483.139438964588, 7635.923640763522, 8197.83604327922, 8546.2308392245, 8548.786280342792, 8553.95723021381, 8565.400421970402, 6440.677966101583, 7382.667779950022, 7453.850922981618, 7501.249875012455, 7541.132769731332, 7424.886267059768, 7452.3665488431925, 7480.850382992201, 7489.588072656056, 7518.774436766906, 6886.311368863074, 7045.859082818418, 7048.506604537838, 7049.823754406181, 7058.082449281561, 4201.495820501559, 4214.4310518079665, 4993.625478089085, 5017.071841709149, 5089.541941225308, 4069.450624165818, 4964.726940018189, 5092.388913330424, 5160.277561141518, 5166.0], "value": 4582.801735109665, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.1484402654315, 300.36922462828056, 310.80574640849477, 312.19609505523283, 314.1909582824228, 4728.895641439561, 5166.121759300919, 5173.741312934357, 5188.558972487343, 5188.6367954243215, 3739.4951681522984, 3934.5081864766903, 5012.170557746424, 5059.621977670489, 5195.0, 3268.4607039838397, 3554.7689400188906, 3711.8634753633137, 3928.292907276689, 3999.9000524724606, 3946.9407958880497, 3947.664448521879, 3969.8809035728973, 3984.740991835536, 3989.301872172373, 3649.6532829381167, 3680.392735198689, 3795.0891785971307, 3901.4928059352246, 3937.27160475313, 2931.9120426387244, 3926.5680775114715, 3929.4891664083484, 3988.6011398860173, 4000.919981600366, 3120.2823350629255, 3189.0751682012187, 3409.3010932758725, 3433.364827506919, 3442.862285508577, 3923.8430462781453, 4857.130004948085, 5051.393832740095, 5056.317397151374, 5091.338126043628, 3751.568569614502, 4230.8519201827785, 4509.774511274423, 4521.909561808779, 4539.345826609578, 4929.260610908356, 4945.9258111128, 5208.5312321891215, 5344.973275133639, 5438.374586922515, 3432.0390290718638, 3642.471841582982, 3804.524434445694, 3824.1969186470874, 3826.0243637872163, 4834.758262086882, 5323.52088312046, 5619.690916999522, 5637.661740295595, 5718.34239062509, 3423.9828800856335, 3609.819509024538, 3802.4106263529197, 3812.84748610058, 3816.0, 3492.1967947372245, 3895.9610403896163, 3913.9217215655526, 3917.0, 3947.289487892207, 3781.7352785305316, 4282.486101667758, 4498.887527811831, 4501.864944051684, 4539.7049191802425, 4425.690201685851, 5313.884084342331, 5362.812301569505, 5414.7834086636885, 5443.972780136153, 1131.2081542919989, 1295.585412667956, 1327.482281910056, 1328.7408955253634, 1336.906416550832, 819.610684924656, 838.7441830241835, 841.7264389073575, 873.4977388001925, 874.7813046738315, 4551.3173024046, 4833.927491087637, 4904.485029071968, 4913.034786085523, 5042.697438153723, 5454.972725136428, 5503.871706300199, 5509.47659972302, 5516.475934786189, 5543.307086614173, 4569.766163135935, 4670.462896766915, 5004.874878128003, 5012.273220383024, 5015.470281564158, 2349.1777877742993, 2706.499297629945, 2730.5358089124898, 2865.226388874992, 2894.9276268093467, 4789.449213340441, 5011.674241174312, 5220.536441342673, 5450.754716037788, 5647.6893770843, 4742.976285118552, 5040.747962601855, 5249.790008399697, 5347.433232048638, 5668.0647693130995, 5287.947120528823, 5665.631733937364, 5694.320175548223, 5710.914336284876, 5715.028445164332, 4607.40103786519, 5447.237386765777, 5484.314460692413, 5502.339719233718, 5565.805196818092, 4481.843135490242, 4650.511696271843, 5165.302684137593, 5447.836564902901, 5496.450354964471, 4713.6431784107945, 5045.84862454127, 5378.601563593415, 5638.464345887134, 5698.886022279448, 3408.9432275994523, 3774.414965680324, 3825.7895815730403, 3835.980820095882, 3868.883933482, 4488.730676159441, 5062.5093390447955, 5548.445155484581, 5561.332640083216, 5580.776768929198, 4774.819321036714, 4897.877553061202, 5209.400918894224, 5300.496452836975, 5300.5759539236, 4515.367848501278, 4724.952750472588, 4963.429205641253, 5339.919901201408, 5359.812406565751, 4360.454943132108, 5262.47401247411, 5263.710495922761, 5264.605154613496, 5268.78924843002, 4430.3575981483145, 4563.315502674625, 5020.297158397901, 5212.843614691566, 5342.278792362981, 4066.1905452074725, 5128.487151284841, 5226.268322434787, 5248.868778280574, 5250.871062721533], "value": 3891.563264552111, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.7216349541928, 716.9677364518597, 742.498813301022, 780.1262585903785, 816.6202715737181, 15258.771118433218, 16659.0, 16670.58323541912, 16682.165891705426, 16694.7495787563, 14961.625959351022, 15082.245887705598, 15103.320350584223, 15110.395584176647, 15118.924405377958, 674.2212744280364, 693.9479539034568, 832.5046597274642, 838.7777239031661, 855.6278019061697, 781.4803155901319, 783.9137694853562, 831.1356189562847, 845.049319515545, 846.9915300846974, 897.5467388968594, 898.3172788680585, 899.3569597737617, 900.4462255712755, 901.9774505637346, 701.4633805139073, 867.3928250224868, 892.5314210039717, 894.6019021535419, 895.1898531828706, 656.6519744535403, 657.9309172536863, 657.9440747536418, 659.4197106546209, 659.4888961055173, 12184.0, 14921.701565968728, 14929.178895160761, 14929.477468288665, 14931.850681493155, 13906.930465347608, 14077.788833167511, 14121.435142594284, 14248.430062797579, 14535.854641453556, 10757.031867131918, 12350.567730129402, 12446.128770986035, 12677.492900283978, 12871.970242380587, 3917.5298964124486, 4481.708688935209, 4553.066621342617, 4573.474050484204, 4771.570558649738, 98225.0, 99076.0, 99524.50237748837, 100355.49822250842, 106011.4699426498, 10965.451727413598, 11334.036606888507, 11351.489182986785, 11379.601713939972, 11412.201145919871, 22072.88963555172, 22091.779082208974, 22094.33716988493, 22112.557748844934, 22155.446113847283, 6907.34380233877, 8046.235607617267, 8067.515949043076, 8154.510729356258, 8233.876491852627, 4432.490263619725, 4432.711873728197, 4530.139273538083, 4673.901633116215, 4676.321933319649, 3541.327147841902, 3681.2637472505608, 3691.151035261851, 3693.8891833245048, 3698.8890333290046, 653.7450394346209, 681.4684546054086, 683.0164563029222, 684.4250829303409, 684.8835697931364, 566.5580846939393, 602.9457348838625, 621.5773274173605, 622.7664625765248, 638.5274896576593, 8298.0, 8393.832123357499, 8500.064992850781, 8953.865692014626, 9187.0, 497.62926619668576, 506.5770081981513, 519.2315373247615, 577.6573225978776, 600.4685853020109, 3513.402721537345, 4312.870613881589, 4334.0, 4400.992526336129, 4479.417675702138, 95749.52125239329, 126524.36737816436, 144344.0, 146389.89711131767, 155262.0, 124993.0, 128878.99999999812, 130179.0, 131005.34497327643, 133180.0, 105161.94838051674, 109851.35222971662, 113264.0, 113638.0, 115066.0, 8107.391945603986, 8215.958920205361, 8300.501969881827, 8319.875201872097, 8449.704260350858, 60775.08837367532, 62503.06245406232, 62830.68584657047, 63407.0, 65025.841988983564, 85067.49325067444, 88213.0, 96361.51819240859, 96595.0340496586, 98242.5087874556, 9762.219022478326, 11186.440677965906, 11316.37759923212, 11678.474468648761, 11709.238899471507, 12472.0, 12500.749985000068, 12590.748185036431, 13010.349482526026, 13037.934810325887, 11696.298222106701, 12092.697682558006, 12190.634280971584, 12271.877281227073, 12367.257964522158, 11694.0, 11906.748123023659, 12024.398780060787, 12138.150329476852, 12326.876731232931, 11621.825672614748, 11843.407829608657, 12027.097967652637, 12294.385280736105, 12328.75342493163, 9707.609888336545, 9764.658236961672, 9821.803563928539, 9838.803223935623, 10233.937036465948, 7774.367382849665, 9981.650642227778, 10053.082971854234, 10055.225487459593, 10074.449864931656], "value": 7545.066940958069, "unit": "score"}], "name": "dromaeo_css", "value": 7288.976089914975, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 113.0, 113.0, 113.0, 111.0, 112.0, 113.0, 112.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 108.0, 108.0, 166.0, 108.0, 107.0, 126.0, 106.0, 140.0, 107.0], "value": 121.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 186.0, 183.0, 183.0, 184.0, 181.0, 181.0, 185.0, 182.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 60.0, 102.0, 83.0, 84.0, 83.0, 63.0, 81.0, 56.0], "value": 76.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 91.0, 96.0, 73.0, 80.0, 69.0, 96.0, 71.0, 68.0, 102.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 91.0, 89.0, 134.0, 128.0, 133.0, 86.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 96.0, 95.0, 94.0, 101.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 86.0, 85.0, 86.0, 84.0, 84.0, 85.0, 104.0], "value": 87.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 55.0, 53.0, 54.0, 55.0, 54.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 75.0, 77.0, 67.0, 70.0, 69.0, 67.0, 67.0, 68.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 100.0, 103.0, 93.0, 87.0, 83.0, 86.0, 88.0, 89.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 135.0, 139.0, 138.0, 154.0, 150.0, 143.0, 147.0, 145.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 170.0, 168.0, 168.0, 169.0, 169.0, 169.0, 170.0, 167.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 67.0, 70.0, 79.0, 88.0, 88.0, 87.0, 70.0], "value": 75.2, "unit": "score"}], "name": "kraken", "value": 1459.1000000000001, "alertThreshold": 2.0}]} 09:53:11 INFO - Return code: 0 09:53:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:11 INFO - # TBPL SUCCESS # 09:53:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:53:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:53:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:53:11 INFO - 2016-08-19 09:53:11.307 screenresolution[1663:10831] starting screenresolution argv=screenresolution get 09:53:11 INFO - 2016-08-19 09:53:11.310 screenresolution[1663:10831] Display 0: 1600x1200x32@60 09:53:11 INFO - 2016-08-19 09:53:11.316 screenresolution[1664:10834] starting screenresolution argv=screenresolution list 09:53:11 INFO - Available Modes on Display 0 09:53:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:53:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:53:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:53:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:53:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:53:11 INFO - Intel Iris: 09:53:11 INFO - Chipset Model: Intel Iris 09:53:11 INFO - Type: GPU 09:53:11 INFO - Bus: Built-In 09:53:11 INFO - VRAM (Dynamic, Max): 1536 MB 09:53:11 INFO - Vendor: Intel (0x8086) 09:53:11 INFO - Device ID: 0x0a2e 09:53:11 INFO - Revision ID: 0x0009 09:53:11 INFO - Displays: 09:53:11 INFO - Display: 09:53:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:53:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:53:11 INFO - Display Serial Number: 1600x1200 60 09:53:11 INFO - Main Display: Yes 09:53:11 INFO - Mirror: Off 09:53:11 INFO - Online: Yes 09:53:11 INFO - Rotation: Supported 09:53:11 INFO - Return code: 0 09:53:11 INFO - Running post-action listener: _resource_record_post_action 09:53:11 INFO - [mozharness: 2016-08-19 16:53:11.553046Z] Finished run-tests step (success) 09:53:11 INFO - Running post-run listener: _resource_record_post_run 09:53:11 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37114880; Write bytes: 704474112; Read time: 239; Write time: 2013 09:53:11 INFO - TinderboxPrint: CPU usage
53.5% 09:53:11 INFO - TinderboxPrint: I/O read bytes / time
37,114,880 / 239 09:53:11 INFO - TinderboxPrint: I/O write bytes / time
704,474,112 / 2,013 09:53:11 INFO - TinderboxPrint: CPU idle
2,255.0 (46.5%) 09:53:11 INFO - TinderboxPrint: CPU system
971.7 (20.0%) 09:53:11 INFO - TinderboxPrint: CPU user
1,623.0 (33.5%) 09:53:11 INFO - TinderboxPrint: Swap in / out
442,765,312 / 0 09:53:11 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 386470400; Write bytes: 378136064; Read time: 29063; Write time: 803 09:53:11 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 36160512; Write bytes: 296687104; Read time: 226; Write time: 1139 09:53:12 INFO - Running post-run listener: _upload_blobber_files 09:53:12 INFO - Blob upload gear active. 09:53:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:53:12 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:53:12 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:53:12 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:53:12 INFO - (blobuploader) - INFO - Open directory for files ... 09:53:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:53:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:53:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:13 INFO - (blobuploader) - INFO - Done attempting. 09:53:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:53:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:53:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:14 INFO - (blobuploader) - INFO - Done attempting. 09:53:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:53:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:53:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:16 INFO - (blobuploader) - INFO - Done attempting. 09:53:16 INFO - (blobuploader) - INFO - Iteration through files over. 09:53:16 INFO - Return code: 0 09:53:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:53:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:53:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ee1a2e56fd657c53f56728ed3ef11ad02e8179baacc38b6adf0789c14e9516a23d8ee1763bda5fce2677f15b6277fc30b6cf4c19b35d41b206b30ad823349e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85eedf00f128e5b26bc3b5bcff6af7e5b25e33715fae29951d26b45f84a5451358cb18e20f19d91f2e72c01aa61f4f765eeab4beaf5677d55719cd4a4e2dba6d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96ba4d5c031f3f4efdd7a29c59fb65454671363684b807c0e78ac3ebee701ac4ac9bbe4bed1717433d9aa8a0263005b2d56f0e2a686e460bb9303e097234ef2a"} 09:53:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:53:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:53:16 INFO - Contents: 09:53:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ee1a2e56fd657c53f56728ed3ef11ad02e8179baacc38b6adf0789c14e9516a23d8ee1763bda5fce2677f15b6277fc30b6cf4c19b35d41b206b30ad823349e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85eedf00f128e5b26bc3b5bcff6af7e5b25e33715fae29951d26b45f84a5451358cb18e20f19d91f2e72c01aa61f4f765eeab4beaf5677d55719cd4a4e2dba6d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96ba4d5c031f3f4efdd7a29c59fb65454671363684b807c0e78ac3ebee701ac4ac9bbe4bed1717433d9aa8a0263005b2d56f0e2a686e460bb9303e097234ef2a"} 09:53:16 INFO - Running post-run listener: copy_logs_to_upload_dir 09:53:16 INFO - Copying logs to upload dir... 09:53:16 INFO - mkdir: /builds/slave/test/build/upload/logs 09:53:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.463163 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-19 09:53:16.928260) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 09:53:16.933057) ========= 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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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/57ee1a2e56fd657c53f56728ed3ef11ad02e8179baacc38b6adf0789c14e9516a23d8ee1763bda5fce2677f15b6277fc30b6cf4c19b35d41b206b30ad823349e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85eedf00f128e5b26bc3b5bcff6af7e5b25e33715fae29951d26b45f84a5451358cb18e20f19d91f2e72c01aa61f4f765eeab4beaf5677d55719cd4a4e2dba6d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96ba4d5c031f3f4efdd7a29c59fb65454671363684b807c0e78ac3ebee701ac4ac9bbe4bed1717433d9aa8a0263005b2d56f0e2a686e460bb9303e097234ef2a"} build_url:https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/MBttbGTkQlO7JMT91zftlw/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/57ee1a2e56fd657c53f56728ed3ef11ad02e8179baacc38b6adf0789c14e9516a23d8ee1763bda5fce2677f15b6277fc30b6cf4c19b35d41b206b30ad823349e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/85eedf00f128e5b26bc3b5bcff6af7e5b25e33715fae29951d26b45f84a5451358cb18e20f19d91f2e72c01aa61f4f765eeab4beaf5677d55719cd4a4e2dba6d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/96ba4d5c031f3f4efdd7a29c59fb65454671363684b807c0e78ac3ebee701ac4ac9bbe4bed1717433d9aa8a0263005b2d56f0e2a686e460bb9303e097234ef2a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 09:53:16.965976) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:53:16.966293) ========= 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.TgWcXTIucT/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.t7RGkQ7c4S/Listeners TMPDIR=/var/folders/nm/b01fmtbx5f59cgm2djktykb800000w/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.005319 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:53:17.014761) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 09:53:17.015071) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 09:53:17.015418) ========= ========= Total master_lag: 0.18 =========