builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0256 starttime: 1472778831.24 results: success (0) buildid: 20160901163941 builduid: 2532601fc71b4efa8116706f42c363e0 revision: 3f398fa60de08dc4c6c0076105c38135afac9ebd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:51.239989) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:51.240383) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:51.240694) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:51.266335) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:51.266610) ========= 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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-01 18:13:51-- 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% 18.3M=0.001s 2016-09-01 18:13:51 (18.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.162639 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:51.459047) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:51.459367) ========= 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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.027044 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:51.507101) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 18:13:51.507456) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3f398fa60de08dc4c6c0076105c38135afac9ebd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3f398fa60de08dc4c6c0076105c38135afac9ebd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ztjXiqY5NS/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.xV4ffsGgvj/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-01 18:13:51,590 truncating revision to first 12 chars 2016-09-01 18:13:51,590 Setting DEBUG logging. 2016-09-01 18:13:51,590 attempt 1/10 2016-09-01 18:13:51,590 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3f398fa60de0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 18:13:53,003 unpacking tar archive at: autoland-3f398fa60de0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.691080 ========= master_lag: 0.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 18:13:53.575601) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:53.575902) ========= script_repo_revision: 3f398fa60de08dc4c6c0076105c38135afac9ebd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:53.576284) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:53.576564) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 18:13:53.595661) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-01 18:13:53.595956) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ztjXiqY5NS/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.xV4ffsGgvj/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:13:53 INFO - MultiFileLogger online at 20160901 18:13:53 in /builds/slave/test 18:13:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:13:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:13:53 INFO - {'append_to_log': False, 18:13:53 INFO - 'base_work_dir': '/builds/slave/test', 18:13:53 INFO - 'blob_upload_branch': 'Autoland', 18:13:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:13:53 INFO - 'branch': 'Autoland', 18:13:53 INFO - 'buildbot_json_path': 'buildprops.json', 18:13:53 INFO - 'config_files': ('talos/mac_config.py',), 18:13:53 INFO - 'default_actions': ('clobber', 18:13:53 INFO - 'read-buildbot-config', 18:13:53 INFO - 'download-and-extract', 18:13:53 INFO - 'populate-webroot', 18:13:53 INFO - 'create-virtualenv', 18:13:53 INFO - 'install', 18:13:53 INFO - 'run-tests'), 18:13:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:13:53 INFO - 'download_minidump_stackwalk': True, 18:13:53 INFO - 'download_symbols': 'ondemand', 18:13:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:13:53 INFO - 'tooltool.py': '/tools/tooltool.py', 18:13:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:13:53 INFO - '/tools/misc-python/virtualenv.py')}, 18:13:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:13:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:13:53 INFO - 'installer_path': 'installer.exe', 18:13:53 INFO - 'log_level': 'info', 18:13:53 INFO - 'log_name': 'talos', 18:13:53 INFO - 'log_to_console': True, 18:13:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:13:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:13:53 INFO - 'opt_config_files': (), 18:13:53 INFO - 'pip_index': False, 18:13:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:13:53 INFO - 'cmd': ('bash', 18:13:53 INFO - '-c', 18:13:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:13:53 INFO - 'enabled': True, 18:13:53 INFO - 'halt_on_failure': False, 18:13:53 INFO - 'name': 'check_screen_resolution'},), 18:13:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:13:53 INFO - 'cmd': ('bash', 18:13:53 INFO - '-c', 18:13:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:13:53 INFO - 'enabled': True, 18:13:53 INFO - 'halt_on_failure': False, 18:13:53 INFO - 'name': 'check_screen_resolution'},), 18:13:53 INFO - 'run_cmd_checks_enabled': True, 18:13:53 INFO - 'sps_profile': False, 18:13:53 INFO - 'sps_profile_interval': 0, 18:13:53 INFO - 'suite': 'dromaeojs', 18:13:53 INFO - 'system_bits': '32', 18:13:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:13:53 INFO - 'title': 't-yosemite-r7-0256', 18:13:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:13:53 INFO - 'use_talos_json': True, 18:13:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:13:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:13:53 INFO - 'work_dir': 'build'} 18:13:53 INFO - [mozharness: 2016-09-02 01:13:53.785873Z] Running clobber step. 18:13:53 INFO - Running pre-action listener: _resource_record_pre_action 18:13:53 INFO - Running main action method: clobber 18:13:53 INFO - rmtree: /builds/slave/test/build 18:13:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:13:55 INFO - Running post-action listener: _resource_record_post_action 18:13:55 INFO - [mozharness: 2016-09-02 01:13:55.738556Z] Finished clobber step (success) 18:13:55 INFO - [mozharness: 2016-09-02 01:13:55.738663Z] Running read-buildbot-config step. 18:13:55 INFO - Running pre-action listener: _resource_record_pre_action 18:13:55 INFO - Running main action method: read_buildbot_config 18:13:55 INFO - Using buildbot properties: 18:13:55 INFO - { 18:13:55 INFO - "project": "", 18:13:55 INFO - "product": "firefox", 18:13:55 INFO - "script_repo_revision": "production", 18:13:55 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 18:13:55 INFO - "repository": "", 18:13:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 18:13:55 INFO - "buildid": "20160901163941", 18:13:55 INFO - "pgo_build": "False", 18:13:55 INFO - "basedir": "/builds/slave/test", 18:13:55 INFO - "buildnumber": 200, 18:13:55 INFO - "slavename": "t-yosemite-r7-0256", 18:13:55 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:13:55 INFO - "platform": "yosemite_r7", 18:13:55 INFO - "scheduler": "tests-autoland-macosx64-talos", 18:13:55 INFO - "branch": "autoland", 18:13:55 INFO - "repo_path": "integration/autoland", 18:13:55 INFO - "revision": "3f398fa60de08dc4c6c0076105c38135afac9ebd", 18:13:55 INFO - "stage_platform": "macosx64", 18:13:55 INFO - "builduid": "2532601fc71b4efa8116706f42c363e0", 18:13:55 INFO - "slavebuilddir": "test" 18:13:55 INFO - } 18:13:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:13:55 INFO - Running post-action listener: _resource_record_post_action 18:13:55 INFO - [mozharness: 2016-09-02 01:13:55.741611Z] Finished read-buildbot-config step (success) 18:13:55 INFO - [mozharness: 2016-09-02 01:13:55.741716Z] Running download-and-extract step. 18:13:55 INFO - Running pre-action listener: _resource_record_pre_action 18:13:55 INFO - Running main action method: download_and_extract 18:13:55 INFO - mkdir: /builds/slave/test/build/tests 18:13:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:55 INFO - trying https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:13:55 INFO - Downloading https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/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 18:13:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/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 18:13:56 INFO - Downloaded 1199 bytes. 18:13:56 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:13:56 INFO - Using the following test package requirements: 18:13:56 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:13:56 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:13:56 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:13:56 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:13:56 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:13:56 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:13:56 INFO - u'jsshell-mac.zip'], 18:13:56 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:13:56 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:13:56 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:13:56 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:13:56 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:13:56 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:13:56 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:13:56 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:13:56 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:13:56 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:13:56 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:13:56 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:13:56 INFO - trying https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:13:56 INFO - Downloading https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/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 18:13:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/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 18:13:57 INFO - Downloaded 16399242 bytes. 18:13:57 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:14:04 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:14: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')]} 18:14:04 INFO - trying https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:14:04 INFO - Downloading https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/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 18:14:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/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 18:14:05 INFO - Downloaded 16399242 bytes. 18:14:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:12 INFO - trying https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:14:12 INFO - Downloading https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/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 18:14:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/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 18:14:13 INFO - Downloaded 14413998 bytes. 18:14:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:14:13 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')]} 18:14:13 INFO - trying https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:14:13 INFO - Downloading https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:14:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/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 18:14:16 INFO - Downloaded 111578675 bytes. 18:14:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:14:16 INFO - mkdir: /builds/slave/test/properties 18:14:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:14:16 INFO - Writing to file /builds/slave/test/properties/build_url 18:14:16 INFO - Contents: 18:14:16 INFO - build_url:https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:14:17 INFO - Running post-action listener: _resource_record_post_action 18:14:17 INFO - Running post-action listener: set_extra_try_arguments 18:14:17 INFO - [mozharness: 2016-09-02 01:14:17.195066Z] Finished download-and-extract step (success) 18:14:17 INFO - [mozharness: 2016-09-02 01:14:17.195192Z] Running populate-webroot step. 18:14:17 INFO - Running pre-action listener: _resource_record_pre_action 18:14:17 INFO - Running main action method: populate_webroot 18:14:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:14:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:14:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:14:17 INFO - '--tppagecycles', 18:14:17 INFO - '7'], 18:14:17 INFO - 'tests': ['tsvgm']}}, 18:14:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:14:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:14:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:14:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:14:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:17 INFO - 'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:14:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:17 INFO - 'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['damp', 'tps']}, 18:14:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:17 INFO - 'tests': ['damp', 'tps']}, 18:14:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['dromaeo_dom']}, 18:14:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:14:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['basic_compositor_video']}, 18:14:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:14:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['a11yr', 18:14:17 INFO - 'ts_paint', 18:14:17 INFO - 'tpaint', 18:14:17 INFO - 'sessionrestore', 18:14:17 INFO - 'sessionrestore_no_auto_restore', 18:14:17 INFO - 'tabpaint']}, 18:14:17 INFO - 'other-e10s': {'tests': ['a11yr', 18:14:17 INFO - 'ts_paint', 18:14:17 INFO - 'tpaint', 18:14:17 INFO - 'sessionrestore', 18:14:17 INFO - 'sessionrestore_no_auto_restore', 18:14:17 INFO - 'tabpaint']}, 18:14:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:14:17 INFO - 'ts_paint', 18:14:17 INFO - 'tpaint', 18:14:17 INFO - 'sessionrestore', 18:14:17 INFO - 'sessionrestore_no_auto_restore', 18:14:17 INFO - 'tabpaint']}, 18:14:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:14:17 INFO - 'ts_paint', 18:14:17 INFO - 'tpaint', 18:14:17 INFO - 'sessionrestore', 18:14:17 INFO - 'sessionrestore_no_auto_restore', 18:14:17 INFO - 'tabpaint']}, 18:14:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['a11yr', 18:14:17 INFO - 'ts_paint', 18:14:17 INFO - 'tpaint', 18:14:17 INFO - 'sessionrestore', 18:14:17 INFO - 'sessionrestore_no_auto_restore', 18:14:17 INFO - 'tabpaint']}, 18:14:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['a11yr', 18:14:17 INFO - 'ts_paint', 18:14:17 INFO - 'tpaint', 18:14:17 INFO - 'sessionrestore', 18:14:17 INFO - 'sessionrestore_no_auto_restore', 18:14:17 INFO - 'tabpaint']}, 18:14:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['tsvgx', 18:14:17 INFO - 'tsvgr_opacity', 18:14:17 INFO - 'tart', 18:14:17 INFO - 'tscrollx', 18:14:17 INFO - 'cart']}, 18:14:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:14:17 INFO - 'tsvgr_opacity', 18:14:17 INFO - 'tart', 18:14:17 INFO - 'tscrollx', 18:14:17 INFO - 'cart']}, 18:14:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:17 INFO - 'talos_options': ['--disable-e10s'], 18:14:17 INFO - 'tests': ['tp5o']}, 18:14:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:17 INFO - 'tests': ['tp5o']}, 18:14:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:17 INFO - 'talos_options': ['--disable-e10s', 18:14:17 INFO - '--xperf_path', 18:14:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:17 INFO - 'tests': ['tp5n']}, 18:14:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:14:17 INFO - 'talos_options': ['--xperf_path', 18:14:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:14:17 INFO - 'tests': ['tp5n']}}, 18:14:17 INFO - 'talos.zip': {'path': '', 18:14:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:14:17 INFO - Running post-action listener: _resource_record_post_action 18:14:17 INFO - [mozharness: 2016-09-02 01:14:17.214549Z] Finished populate-webroot step (success) 18:14:17 INFO - [mozharness: 2016-09-02 01:14:17.214657Z] Running create-virtualenv step. 18:14:17 INFO - Running pre-action listener: _resource_record_pre_action 18:14:17 INFO - Running main action method: create_virtualenv 18:14:17 INFO - Creating virtualenv /builds/slave/test/build/venv 18:14:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:14:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:14:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:14:17 INFO - Using real prefix '/tools/python27' 18:14:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:14:18 INFO - Installing distribute.............................................................................................................................................................................................done. 18:14:20 INFO - Installing pip.................done. 18:14:20 INFO - Return code: 0 18:14:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:14:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:14:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:20 INFO - 'HOME': '/Users/cltbld', 18:14:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:20 INFO - 'LOGNAME': 'cltbld', 18:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:20 INFO - 'MOZ_NO_REMOTE': '1', 18:14:20 INFO - 'NO_EM_RESTART': '1', 18:14:20 INFO - 'PAGER': '/bin/cat', 18:14:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:20 INFO - 'PWD': '/builds/slave/test', 18:14:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:20 INFO - 'SHELL': '/bin/bash', 18:14:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:20 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:20 INFO - 'USER': 'cltbld', 18:14:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:20 INFO - 'XPC_FLAGS': '0x0', 18:14:20 INFO - 'XPC_SERVICE_NAME': '0', 18:14:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:14:21 INFO - Cleaning up... 18:14:21 INFO - Return code: 0 18:14:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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')]} 18:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:14:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:21 INFO - 'HOME': '/Users/cltbld', 18:14:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:21 INFO - 'LOGNAME': 'cltbld', 18:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:21 INFO - 'MOZ_NO_REMOTE': '1', 18:14:21 INFO - 'NO_EM_RESTART': '1', 18:14:21 INFO - 'PAGER': '/bin/cat', 18:14:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:21 INFO - 'PWD': '/builds/slave/test', 18:14:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:21 INFO - 'SHELL': '/bin/bash', 18:14:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:21 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:21 INFO - 'USER': 'cltbld', 18:14:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:21 INFO - 'XPC_FLAGS': '0x0', 18:14:21 INFO - 'XPC_SERVICE_NAME': '0', 18:14:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:21 INFO - Downloading/unpacking psutil>=3.1.1 18:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:14:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:24 INFO - Installing collected packages: psutil 18:14:24 INFO - Running setup.py install for psutil 18:14:24 INFO - building 'psutil._psutil_osx' extension 18:14:24 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 18:14:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:14:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:14:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:14:25 INFO - building 'psutil._psutil_posix' extension 18:14:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:14:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:14:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:14:25 INFO - ^ 18:14:25 INFO - 1 warning generated. 18:14:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:14:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:14:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:14:25 INFO - Successfully installed psutil 18:14:25 INFO - Cleaning up... 18:14:25 INFO - Return code: 0 18:14:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:14:25 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')]} 18:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:25 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')]} 18:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:25 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:25 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 18:14:25 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 18:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:25 INFO - 'HOME': '/Users/cltbld', 18:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:25 INFO - 'LOGNAME': 'cltbld', 18:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:25 INFO - 'MOZ_NO_REMOTE': '1', 18:14:25 INFO - 'NO_EM_RESTART': '1', 18:14:25 INFO - 'PAGER': '/bin/cat', 18:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:25 INFO - 'PWD': '/builds/slave/test', 18:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:25 INFO - 'SHELL': '/bin/bash', 18:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:25 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:25 INFO - 'USER': 'cltbld', 18:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:25 INFO - 'XPC_FLAGS': '0x0', 18:14:25 INFO - 'XPC_SERVICE_NAME': '0', 18:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:14:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:14:28 INFO - Installing collected packages: mozsystemmonitor 18:14:28 INFO - Running setup.py install for mozsystemmonitor 18:14:28 INFO - Successfully installed mozsystemmonitor 18:14:28 INFO - Cleaning up... 18:14:28 INFO - Return code: 0 18:14:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:28 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:28 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 18:14:28 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 18:14:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:28 INFO - 'HOME': '/Users/cltbld', 18:14:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:28 INFO - 'LOGNAME': 'cltbld', 18:14:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:28 INFO - 'MOZ_NO_REMOTE': '1', 18:14:28 INFO - 'NO_EM_RESTART': '1', 18:14:28 INFO - 'PAGER': '/bin/cat', 18:14:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:28 INFO - 'PWD': '/builds/slave/test', 18:14:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:28 INFO - 'SHELL': '/bin/bash', 18:14:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:28 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:28 INFO - 'USER': 'cltbld', 18:14:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:28 INFO - 'XPC_FLAGS': '0x0', 18:14:28 INFO - 'XPC_SERVICE_NAME': '0', 18:14:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:28 INFO - Downloading/unpacking jsonschema==2.5.1 18:14:28 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 18:14:28 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 18:14:28 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 18:14:28 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 18:14:28 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 18:14:28 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 18:14:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:14:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:14:31 INFO - Downloading functools32-3.2.3-2.tar.gz 18:14:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:14:31 INFO - warning: no files found matching '*.txt' 18:14:31 INFO - no previously-included directories found matching 'build' 18:14:31 INFO - no previously-included directories found matching 'dist' 18:14:31 INFO - no previously-included directories found matching '.git*' 18:14:31 INFO - Installing collected packages: jsonschema, functools32 18:14:31 INFO - Running setup.py install for functools32 18:14:31 INFO - warning: no files found matching '*.txt' 18:14:31 INFO - no previously-included directories found matching 'build' 18:14:31 INFO - no previously-included directories found matching 'dist' 18:14:31 INFO - no previously-included directories found matching '.git*' 18:14:31 INFO - Successfully installed jsonschema functools32 18:14:31 INFO - Cleaning up... 18:14:32 INFO - Return code: 0 18:14:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:32 INFO - 'HOME': '/Users/cltbld', 18:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:32 INFO - 'LOGNAME': 'cltbld', 18:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:32 INFO - 'MOZ_NO_REMOTE': '1', 18:14:32 INFO - 'NO_EM_RESTART': '1', 18:14:32 INFO - 'PAGER': '/bin/cat', 18:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:32 INFO - 'PWD': '/builds/slave/test', 18:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:32 INFO - 'SHELL': '/bin/bash', 18:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:32 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:32 INFO - 'USER': 'cltbld', 18:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:32 INFO - 'XPC_FLAGS': '0x0', 18:14:32 INFO - 'XPC_SERVICE_NAME': '0', 18:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:14:32 INFO - Cleaning up... 18:14:32 INFO - Return code: 0 18:14:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:32 INFO - 'HOME': '/Users/cltbld', 18:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:32 INFO - 'LOGNAME': 'cltbld', 18:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:32 INFO - 'MOZ_NO_REMOTE': '1', 18:14:32 INFO - 'NO_EM_RESTART': '1', 18:14:32 INFO - 'PAGER': '/bin/cat', 18:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:32 INFO - 'PWD': '/builds/slave/test', 18:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:32 INFO - 'SHELL': '/bin/bash', 18:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:32 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:32 INFO - 'USER': 'cltbld', 18:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:32 INFO - 'XPC_FLAGS': '0x0', 18:14:32 INFO - 'XPC_SERVICE_NAME': '0', 18:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:32 INFO - Downloading/unpacking blobuploader==1.2.4 18:14:32 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 18:14:32 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 18:14:32 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 18:14:32 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 18:14:32 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 18:14:32 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 18:14:34 INFO - Downloading blobuploader-1.2.4.tar.gz 18:14:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:14:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:14:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14: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 18:14:36 INFO - Downloading docopt-0.6.1.tar.gz 18:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:14:36 INFO - Installing collected packages: blobuploader, requests, docopt 18:14:36 INFO - Running setup.py install for blobuploader 18:14:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:14:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:14:36 INFO - Running setup.py install for requests 18:14:36 INFO - Running setup.py install for docopt 18:14:37 INFO - Successfully installed blobuploader requests docopt 18:14:37 INFO - Cleaning up... 18:14:37 INFO - Return code: 0 18:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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')]} 18:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:37 INFO - 'HOME': '/Users/cltbld', 18:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:37 INFO - 'LOGNAME': 'cltbld', 18:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:37 INFO - 'MOZ_NO_REMOTE': '1', 18:14:37 INFO - 'NO_EM_RESTART': '1', 18:14:37 INFO - 'PAGER': '/bin/cat', 18:14:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:37 INFO - 'PWD': '/builds/slave/test', 18:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:37 INFO - 'SHELL': '/bin/bash', 18:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:37 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:37 INFO - 'USER': 'cltbld', 18:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:37 INFO - 'XPC_FLAGS': '0x0', 18:14:37 INFO - 'XPC_SERVICE_NAME': '0', 18:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:37 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-YorI7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:37 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-1Qojbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:37 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-PAZmrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:37 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-rOLHJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:37 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-WWnGG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:37 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-UxBU1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:37 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-KTs5Xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:38 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-8T8lt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:38 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-V1E6bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:38 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-Cea0kG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:38 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-5PbKOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:38 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-DNhWvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:38 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-gKehz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:38 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-M2WeMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:38 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-fsLVJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:38 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-SJL8aL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:39 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-8hBtwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:14:39 INFO - Running setup.py install for manifestparser 18:14:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:14:39 INFO - Running setup.py install for mozcrash 18:14:39 INFO - Running setup.py install for mozdebug 18:14:39 INFO - Running setup.py install for mozdevice 18:14:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:14:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:14:39 INFO - Running setup.py install for mozfile 18:14:39 INFO - Running setup.py install for mozhttpd 18:14:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:14:40 INFO - Running setup.py install for mozinfo 18:14:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:14:40 INFO - Running setup.py install for mozInstall 18:14:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:14:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:14:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:14:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:14:40 INFO - Running setup.py install for mozleak 18:14:40 INFO - Running setup.py install for mozlog 18:14:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:14:40 INFO - Running setup.py install for moznetwork 18:14:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:14:40 INFO - Running setup.py install for mozprocess 18:14:40 INFO - Running setup.py install for mozprofile 18:14:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:14:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:14:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:14:41 INFO - Running setup.py install for mozrunner 18:14:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:14:41 INFO - Running setup.py install for mozscreenshot 18:14:41 INFO - Running setup.py install for moztest 18:14:41 INFO - Running setup.py install for mozversion 18:14:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:14:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:14:41 INFO - Cleaning up... 18:14:41 INFO - Return code: 0 18:14:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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')]} 18:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:41 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 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:41 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 18:14:41 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 18:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:41 INFO - 'HOME': '/Users/cltbld', 18:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:41 INFO - 'LOGNAME': 'cltbld', 18:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:41 INFO - 'MOZ_NO_REMOTE': '1', 18:14:41 INFO - 'NO_EM_RESTART': '1', 18:14:41 INFO - 'PAGER': '/bin/cat', 18:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:41 INFO - 'PWD': '/builds/slave/test', 18:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:41 INFO - 'SHELL': '/bin/bash', 18:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:41 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:41 INFO - 'USER': 'cltbld', 18:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:41 INFO - 'XPC_FLAGS': '0x0', 18:14:41 INFO - 'XPC_SERVICE_NAME': '0', 18:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:14:41 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-MiYLtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:14:42 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-HsIu1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:14:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:14:42 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-uiqMne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:14:42 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)) 18:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:14:42 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-Mlp6dO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:14:42 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)) 18:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:14:42 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-IWux4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:14:42 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)) 18:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:14:42 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-mMHAVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:14:42 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)) 18:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:14:42 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-16ttAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:14:42 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)) 18:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:14:42 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-RdZGy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:14:42 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)) 18:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:14:42 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-d0VCtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:14:42 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)) 18:14:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:14:42 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-cbhJ5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:14:43 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-DM36P5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:14:43 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-Ax9ZqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:14:43 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-zadEch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:14:43 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)) 18:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:14:43 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-UgWVCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:14:43 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)) 18:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:43 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-CGUkxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:14:43 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)) 18:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:14:43 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-uqzrmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:14:43 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)) 18:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:14:43 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-mFnPwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:14:43 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)) 18:14:43 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)) 18:14:43 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)) 18:14:43 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)) 18:14:43 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)) 18:14:43 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)) 18:14:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:14:43 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 18:14:43 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 18:14:43 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 18:14:43 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 18:14:43 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 18:14:43 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 18:14:46 INFO - Downloading blessings-1.6.tar.gz 18:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:14:46 INFO - Installing collected packages: blessings 18:14:46 INFO - Running setup.py install for blessings 18:14:46 INFO - Successfully installed blessings 18:14:46 INFO - Cleaning up... 18:14:46 INFO - Return code: 0 18:14:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:14:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:46 INFO - Reading from file tmpfile_stdout 18:14:46 INFO - Current package versions: 18:14:46 INFO - blessings == 1.6 18:14:46 INFO - blobuploader == 1.2.4 18:14:46 INFO - docopt == 0.6.1 18:14:46 INFO - functools32 == 3.2.3-2 18:14:46 INFO - jsonschema == 2.5.1 18:14:46 INFO - manifestparser == 1.1 18:14:46 INFO - mozInstall == 1.12 18:14:46 INFO - mozcrash == 0.17 18:14:46 INFO - mozdebug == 0.1 18:14:46 INFO - mozdevice == 0.48 18:14:46 INFO - mozfile == 1.2 18:14:46 INFO - mozhttpd == 0.7 18:14:46 INFO - mozinfo == 0.9 18:14:46 INFO - mozleak == 0.1 18:14:46 INFO - mozlog == 3.3 18:14:46 INFO - moznetwork == 0.27 18:14:46 INFO - mozprocess == 0.23 18:14:46 INFO - mozprofile == 0.28 18:14:46 INFO - mozrunner == 6.12 18:14:46 INFO - mozscreenshot == 0.1 18:14:46 INFO - mozsystemmonitor == 0.3 18:14:46 INFO - moztest == 0.7 18:14:46 INFO - mozversion == 1.4 18:14:46 INFO - psutil == 3.1.1 18:14:46 INFO - requests == 1.2.3 18:14:46 INFO - wsgiref == 0.1.2 18:14:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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')]} 18:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:47 INFO - 'HOME': '/Users/cltbld', 18:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:47 INFO - 'LOGNAME': 'cltbld', 18:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:47 INFO - 'MOZ_NO_REMOTE': '1', 18:14:47 INFO - 'NO_EM_RESTART': '1', 18:14:47 INFO - 'PAGER': '/bin/cat', 18:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:47 INFO - 'PWD': '/builds/slave/test', 18:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:47 INFO - 'SHELL': '/bin/bash', 18:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:47 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:47 INFO - 'USER': 'cltbld', 18:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:47 INFO - 'XPC_FLAGS': '0x0', 18:14:47 INFO - 'XPC_SERVICE_NAME': '0', 18:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:14:47 INFO - Cleaning up... 18:14:47 INFO - Return code: 0 18:14:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:14: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')]} 18:14:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:14:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14: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')]} 18:14:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:14:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:14:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10826ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108301618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa921c954d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, '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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:14:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:14:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:14:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:14:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:14:47 INFO - 'HOME': '/Users/cltbld', 18:14:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:14:47 INFO - 'LOGNAME': 'cltbld', 18:14:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:14:47 INFO - 'MOZ_NO_REMOTE': '1', 18:14:47 INFO - 'NO_EM_RESTART': '1', 18:14:47 INFO - 'PAGER': '/bin/cat', 18:14:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:14:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:14:47 INFO - 'PWD': '/builds/slave/test', 18:14:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:14:47 INFO - 'SHELL': '/bin/bash', 18:14:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:14:47 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:14:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:14:47 INFO - 'USER': 'cltbld', 18:14:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:14:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:14:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:14:47 INFO - 'XPC_FLAGS': '0x0', 18:14:47 INFO - 'XPC_SERVICE_NAME': '0', 18:14:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:14:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:14:47 INFO - Cleaning up... 18:14:47 INFO - Return code: 0 18:14:47 INFO - Running post-action listener: _resource_record_post_action 18:14:47 INFO - Running post-action listener: _start_resource_monitoring 18:14:47 INFO - Starting resource monitoring. 18:14:47 INFO - [mozharness: 2016-09-02 01:14:47.787620Z] Finished create-virtualenv step (success) 18:14:47 INFO - [mozharness: 2016-09-02 01:14:47.788657Z] Running install step. 18:14:47 INFO - Running pre-action listener: _resource_record_pre_action 18:14:47 INFO - Running main action method: install 18:14:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:14:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:14:48 INFO - Reading from file tmpfile_stdout 18:14:48 INFO - Detecting whether we're running mozinstall >=1.0... 18:14:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:14:48 INFO - Reading from file tmpfile_stdout 18:14:48 INFO - Output received: 18:14:48 INFO - Usage: mozinstall [options] installer 18:14:48 INFO - Options: 18:14:48 INFO - -h, --help show this help message and exit 18:14:48 INFO - -d DEST, --destination=DEST 18:14:48 INFO - Directory to install application into. [default: 18:14:48 INFO - "/builds/slave/test"] 18:14:48 INFO - --app=APP Application being installed. [default: firefox] 18:14:48 INFO - mkdir: /builds/slave/test/build/application 18:14:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:15:22 INFO - Reading from file tmpfile_stdout 18:15:22 INFO - Output received: 18:15:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:15:22 INFO - Running post-action listener: _resource_record_post_action 18:15:22 INFO - [mozharness: 2016-09-02 01:15:22.428292Z] Finished install step (success) 18:15:22 INFO - [mozharness: 2016-09-02 01:15:22.428409Z] Running run-tests step. 18:15:22 INFO - Running pre-action listener: _resource_record_pre_action 18:15:22 INFO - Running main action method: run_tests 18:15:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:15:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:15:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:15:22 INFO - 2016-09-01 18:15:22.442 screenresolution[1636:7427] starting screenresolution argv=screenresolution get 18:15:22 INFO - 2016-09-01 18:15:22.445 screenresolution[1636:7427] Display 0: 1600x1200x32@60 18:15:22 INFO - 2016-09-01 18:15:22.451 screenresolution[1637:7430] starting screenresolution argv=screenresolution list 18:15:22 INFO - Available Modes on Display 0 18:15:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:15:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:15:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:15:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:15:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:15:22 INFO - Intel Iris: 18:15:22 INFO - Chipset Model: Intel Iris 18:15:22 INFO - Type: GPU 18:15:22 INFO - Bus: Built-In 18:15:22 INFO - VRAM (Dynamic, Max): 1536 MB 18:15:22 INFO - Vendor: Intel (0x8086) 18:15:22 INFO - Device ID: 0x0a2e 18:15:22 INFO - Revision ID: 0x0009 18:15:22 INFO - Displays: 18:15:22 INFO - Display: 18:15:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:15:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:15:22 INFO - Display Serial Number: 1600x1200 60 18:15:22 INFO - Main Display: Yes 18:15:22 INFO - Mirror: Off 18:15:22 INFO - Online: Yes 18:15:22 INFO - Rotation: Supported 18:15:22 INFO - Return code: 0 18:15:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:15:22 INFO - Python 2.7.3 18:15:22 INFO - Return code: 0 18:15:22 INFO - grabbing minidump binary from tooltool 18:15: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')]} 18:15:22 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1081d0d30>, '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 0x107f35db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1081c8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:15:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:15:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:15:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:15:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:15:22 INFO - Return code: 0 18:15:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:15:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:15:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:15:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:15:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:15:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--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 18:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0256 --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 18:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ztjXiqY5NS/Render', 18:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:15:22 INFO - 'HOME': '/Users/cltbld', 18:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:15:22 INFO - 'LOGNAME': 'cltbld', 18:15:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:15:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:22 INFO - 'MOZ_NO_REMOTE': '1', 18:15:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:15:22 INFO - 'NO_EM_RESTART': '1', 18:15:22 INFO - 'PAGER': '/bin/cat', 18:15:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:22 INFO - 'PWD': '/builds/slave/test', 18:15:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:15:22 INFO - 'SHELL': '/bin/bash', 18:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xV4ffsGgvj/Listeners', 18:15:22 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:15:22 INFO - 'USER': 'cltbld', 18:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:22 INFO - 'XPC_FLAGS': '0x0', 18:15:22 INFO - 'XPC_SERVICE_NAME': '0', 18:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:15:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--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 18:15:23 INFO - mozversion application_buildid: 20160901163941 18:15:23 INFO - mozversion application_changeset: 3f398fa60de08dc4c6c0076105c38135afac9ebd 18:15:23 INFO - mozversion application_display_name: Nightly 18:15:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:15:23 INFO - mozversion application_name: Firefox 18:15:23 INFO - mozversion application_remotingname: firefox 18:15:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:15:23 INFO - mozversion application_vendor: Mozilla 18:15:23 INFO - mozversion application_version: 51.0a1 18:15:23 INFO - mozversion platform_buildid: 20160901163941 18:15:23 INFO - mozversion platform_changeset: 3f398fa60de08dc4c6c0076105c38135afac9ebd 18:15:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:15:23 INFO - mozversion platform_version: 51.0a1 18:15:23 INFO - using testdate: 1472778923 18:15:23 INFO - actual date: 1472778923 18:15:23 INFO - starting webserver on 'localhost:49274' 18:15:23 INFO - SUITE-START | Running 2 tests 18:15:23 INFO - TEST-START | dromaeo_css 18:15:23 INFO - operating with platform_type : mac_ 18:15:23 INFO - Initialising browser for dromaeo_css test... 18:15:23 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpk2JTCl/profile http://localhost:49274/getInfo.html) 18:15:25 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 18:15:25 INFO - PROCESS | 1647 | colorDepth:24 18:15:25 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 18:15:25 INFO - PROCESS | 1647 | __metrics 18:15:25 INFO - PROCESS | 1647 | 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" 18:15:25 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:15:26 INFO - TEST-INFO | 1647: exit 0 18:15:26 INFO - Browser initialized. 18:15:26 INFO - Running cycle 1/1 for dromaeo_css test... 18:15:26 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpk2JTCl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:38 INFO - PROCESS | 1649 | RSS: Main: 234029056 18:15:38 INFO - PROCESS | 1649 | 18:16:29 INFO - PROCESS | 1649 | 1472778989990 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] 18:18:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 18:18:43 INFO - PROCESS | 1649 | RSS: Main: 375238656 18:18:43 INFO - PROCESS | 1649 | 18:21:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 18:21:46 INFO - PROCESS | 1649 | RSS: Main: 377925632 18:21:46 INFO - PROCESS | 1649 | 18:24:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 18:24:49 INFO - PROCESS | 1649 | RSS: Main: 361476096 18:24:49 INFO - PROCESS | 1649 | 18:27:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 18:27:52 INFO - PROCESS | 1649 | RSS: Main: 350011392 18:27:52 INFO - PROCESS | 1649 | 18:30:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 18:30:56 INFO - PROCESS | 1649 | RSS: Main: 324943872 18:30:56 INFO - PROCESS | 1649 | 18:33:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 18:33:59 INFO - PROCESS | 1649 | RSS: Main: 465981440 18:33:59 INFO - PROCESS | 1649 | 18:33:59 INFO - PROCESS | 1649 | __start_tp_report 18:33:59 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 18:33:59 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 18:33:59 INFO - PROCESS | 1649 | |i|pagename|runs| 18:33:59 INFO - PROCESS | 1649 | |0;dojo.html;3319.983400083;3342.7492938029645;3345.2138747394356;3358.781679190851;3409.9147521311966;5600.103983362663;5646.209530665705;5692.544596432286;5737.885242295151;5752.942470575298;5683.658980461165;5692.402297758737;5715;5791.855203619901;5793.855153621162;5106.234064890277;5117.513836185556;5204.167333226685;5218.243354713564;5244.187150991603;5210.400803907562;5244.79020839166;5259.237410575464;5260.079486089938;5300.946990530103;5088.287639730441;5119.820806271762;5133.871653208662;5189.922151167735;5196.844094677166;4709.62323014158;5154.819581314673;5239.764210610531;5258.474152584749;5269.473052694738;5124.948750512484;5158.767855446514;5178.533931946143;5193;5242.397124330712;5567.220589117486;5723.255976723041;5730.36965933747;5748.798792042258;5759.222504961818;5124.795008199668;6131.938680613226;6151.784687535959;6205.8138255851945;6218.906716399258;6211.689415529206;6214.875702485971;6229.719662615194;6251.3123556408755;6283.277423096357;5640.971795140998;5699.059655156852;5749.568782341341;5761.135829625589;5800.042992906166;6230.096635987847;6309.558330916791;6323.30443651198;6348.143000694847;6353.714082866282;6089.238845144357;6099.085137229363;6111.022236442148;6115.633062016204;6149.169862068564;6103.23709536308;6275.6548389839;6276;6291.087792270096;6292.590981586182;4845.685030473008;6103.186149163621;6106.096658337741;6133.334663918993;6153.384661533811;6265.8120256392385;6269.059641053878;6275.780347687809;6280.937190628127;6283.6858157091965;4841.15279826029;4923.212286034217;4926.187179115406;4934.629902757308;4945.554900059012;4347.021920067959;4347.739135651871;4362.672799539983;4415.381846541487;4449.555044495589;5737.3975732548315;5737.627054241461;5737.8565535861935;5738.454846789548;5770.913436298459;6307.779227727007;6316.494680425555;6320.5891617044745;6321.589096708699;6345.333739957238;5564.054110801174;5623.465770751772;5643.238162847963;5656.264685590847;5717.571182161355;5352.892942141136;5402.540784033323;5403.70279634616;5404;5409.5672346213;5998.070299103646;6262.780802671976;6317.526185536011;6334.9366506334345;6361.332060133805;6332.0818481320875;6332.556721029484;6337.302896681269;6344.429001389897;6364.681765911779;6298.527610429328;6312.43188113072;6314.905276420765;6322.715477803418;6352.523560733056;6111.135969295072;6115.8776824462375;6146.139540464428;6194.5354098443695;6216.53375996793;5244.449332819999;6167.876642467215;6237.469815065589;6283.308836027942;6297.307296197323;6114.113453549299;6116.480099191618;6136.938630613637;6142.04798256271;6147.323794382703;5912.911306330322;5969.671668058299;6008.519318454426;6049.062395328731;6091.238595175603;5974.6713930732485;5993.67034813072;5998.220231370068;6003.939960600338;6035.094735789667;6108.450239478469;6125.663088529996;6131.601445906002;6132.78535251264;6133.172021777003;5110.897782044412;5654.6324488908085;5670.461306175906;5919.526437885045;5956.821295361146 18:33:59 INFO - PROCESS | 1649 | |1;ext.html;460.72586810847537;465.8672278400656;482.25973131243546;503.11451844753225;536.8738346488577;31532.527012094808;32406.837965810166;39905.600943990554;41479.37780933289;43583.128337433234;42078.36882446766;42534;42600.721978340625;42626.573734262645;42701.35947960775;8753.518556479392;9536.618535258582;9727.75680607987;9752.561134748918;9764.853527197098;11684.240524365932;11925.642230733049;11932.284062956207;12282.385880705973;12291;11888.7622247555;11902.821457678141;12003.939980300087;12009.099317551176;12029.879701203006;8730.388872778907;11672.299662020307;11944.283342999404;12001;12014.819777703298;11478.885211147865;11881.762364752656;11884.762304753856;11901.642950711492;11916.404179790976;40165.19669606621;40492.59507404947;40505;40526.79736601298;40568.594314057074;3494.598121216042;3705.6479634434686;3749.850005999757;4074.5925407459017;4162.354835000579;30522.236944076354;31141.377172456425;31543.526847097313;31655.525167122512;31759.39488774115;3280.327532855783;3350.27968986669;3353.8764513887877;3362.2098806780386;3399.4390925497264;351583;352399;352510;352685;358007.20996395114;58881;59116;59195.704021479614;59210.40789592135;59226;62404.68797655983;62436.37563624397;62649.68675156595;62678.686606566676;62690;16107.355705771637;16384.180790960403;16384.934979226313;16412.507624771275;16420.671586568442;11468.36923969173;11635.88364116365;12277.483115961206;12655.050871184514;12789.040821938206;16208.594785130466;16947.152642367833;16985.23566439513;17005.57486062858;17025.659486810444;4764.767482449711;5122.487751224922;6121.540884433685;6149.354317796657;6153.384661533811;832.8292699996487;862.9827403451895;990.5933574324476;1066.9786604267872;1109.8446217529552;29158.271043223664;29284.414311714074;29769.553456698166;29948.95178668736;30154;942.7548837302351;1036.43514284715;1084.2789544952518;1110.4342876544033;1155.9595414160465;14440.9169312302;14457.204853733145;15139.772903406238;15256.38974441032;15766.132862692662;150518;183804;184331;184598;184751;45209.77395113069;56518.717406413525;56667.433325666214;56671.14993275104;56731;1276.3235485192863;1327.6946302350514;1333.0402110480827;1337.498438085718;1339.3035621476777;27971;28812.855935721025;29458.263543411587;29528.85235573894;30198.245043874078;50056.24803132019;50266.74866625643;50326.99346013132;50339.204207431525;50394.49605503898;543898;548366;548674;549653;549755;31385.8430707845;34124.488132678525;39913.600863990985;40427.59572404238;40993.59006409898;46296.60182282678;46314.53685463102;46393.53606463892;46671.833204170165;47063;37530.24939501249;38137.23725525529;38236.808815955745;39149;39823.60176398199;37159;38205.80897095497;38208;39329.60670393259;39488;37231.04303311457;38056.42915356217;38387.6161238384;38533.843984680505;38587.22825543417;30374.696253037186;33969.830150849084;33973.83013084919;34064.65935340615;34111.317773644885;24014.919328630604;25170.74829251684;25622.871885640452;25725.742742573082;25823.483530329664 18:33:59 INFO - PROCESS | 1649 | |2;jquery.html;355.1033640058853;368.51724241243954;373.60957799099947;387.0864759168362;407.3828150352217;15209;19263.71104433433;19514.121864516113;20229.190832366687;20493.795062049376;19104.617907641838;20318;20338.49153771153;20523.38429847103;20801;5853.648781073139;6210.130581718563;6212.906806397885;6215.067739839015;6216.37836216377;5344.251804747339;6159.8768024639485;6229.127922090888;6232.314445411024;6251.187345645059;5940.643561386309;6049.909251361233;6185.907211391833;6198.256209254873;6205.22434695663;5128.53843154116;6065.059915713049;6080.391960803929;6135.5398345124295;6136.509079273647;5043.344365232533;5267.631265811395;5295.179247216649;5303.5226829585135;5323.760430780624;25211.11761088353;25438.491230175292;25438.745612543822;25496.36259093519;25526.489470210676;2264.3433404312727;2272.8863556822257;2395.0659242895285;3015.0954713586057;3083.753299736016;12127.514899404014;14334.638209370198;15362.308696108701;15496.225188740518;15997.600059998476;1832.2579355360967;1833.275856036858;1835.5312105218459;1843.815618438159;1852.4905650945964;118365;120228;121456;123361.38319308436;123656.38171809174;9039.23166530839;10282.485875706185;10489.108425783894;10853.782924341469;10867.510962006729;13484;13517.391717372742;13538.729225415436;13551.796723049161;13563;8234.423590348619;8493.830123397622;8501;8641.351898607629;8658.653653853902;6770.966145169243;8526.314211023508;8534.530600817016;8657.091005444463;8727.690846373094;5331.466853314638;6001.489873360813;6557.2131344239;6580.407763301326;6677.265500794909;1667.9833201667827;1769.195016267595;1842.4933143385408;1869.5139263791511;1888.6600411925986;845.446232717571;882.0650110882485;896.4173287363136;899.8155378147464;940.1632547033009;20298;21253.46866328354;21703.782962170175;21743.782562174492;21818.672719909213;939.7415710679481;943.7970836270235;965.3773316211004;983.4492684096929;989.2877128467492;11011;11460.598879039193;11618.244814087057;11640.825387619192;11697.76604467906;516972;555109;559341;567319;567621;81926;102880;107238;108043.91956080339;110425.44787276012;987.6641941739842;987.6987518806833;989.3865803202061;991.2912267728609;1002.2132625888643;18602.627947440706;18890;19073;19098.618027639648;19100.421443105002;37269.25461490701;51529.742351288005;51646.225306621185;52287.47712522826;52416.47583524269;55967;70566;70971;73171;77019.61490192513;6117.908231376444;6486.254080780582;7755.340796032175;8018.759437216893;8221.917780822116;12518.749625007631;12931.870681293067;13322.733545329233;13442.193468391928;13445.46218151263;9183.311251656287;9940.751481213027;10485.895141048797;10629.627963021258;11199.384033878216;10589.682309530726;11013.944930275618;11305.604303849326;11477.08183345347;11827.994620457357;11354.829677554679;11368.715782105513;11696.649100526998;11924.403779811148;12084.154109212272;7899.68401263943;7989.680412783424;8122.187781221831;8234.958825205835;8441.198086181803;8258.339332853477;10636.8936310639;11511.848815118421;11596.884031159918;11652.417379131179 18:33:59 INFO - PROCESS | 1649 | |3;mootools.html;1348.2854087333717;1495.3495229575135;1531.8238402583702;1724.5429961060322;1911.6654585447543;7781.455298129134;8405.579721013948;8848.601812918418;8858.867116993242;8953.552322383888;8722.258608018326;8813.559322033905;8814;8823.823523529525;8828;2352.364861487402;2374.881255937205;2384.3443053160345;2387.092904696213;2387.379281386843;2332.9416764580938;2361.456864921069;2379.607364784817;2384.0463814474174;2385.642153676945;2347.225415612852;2360.209329874494;2370.751071137523;2380.071772008918;2380.880955952204;2321.1759825262125;2344.19125401737;2354.105439932832;2354.540864531417;2369.206315884181;2304.4354133237466;2337.146941366406;2341.9297421077395;2342.1802369170823;2348.5302939412013;8663.523506207157;8750.86873696895;8852.955735221347;8865.468071915706;8881.378303518757;5226.608004399685;5419.241306217133;5423.891522169574;5425.6202065855405;5436.401995780461;3633.9273214535824;3645.070507020719;3703.574088979775;3710.9072273193387;3717.4795528626014;913.2328843771264;1079.578964203961;1088.0370871778455;1100.3672888089382;1103.6633826682857;11592.6522204334;11642.6507204784;11695.590654327058;11728.648140555797;11734.354610496504;4378.496472905656;4506.121306345297;4619.129252652681;4629.166749984967;4631.421072365954;9148.17666410013;9149.63401463934;9151.90848091524;9158.313126515539;9167.312451566158;5248.422673505911;5250.501202385767;5257.132573125468;5258.631895767336;5259.105951988095;4376.168527979674;4523.705959112647;4758.738269395148;4875.049365373789;4926.359573255454;3208.871645134215;3671.2657468506404;3711.554613446404;3780.2061567071223;3812.2375524895133;1157.2362240920952;1170.4849866058908;1172.8358029875824;1180.0677464802882;1181.988180118205;739.6523633892062;775.0892701076212;832.2094010689868;885.2165833237631;885.822835432916;5465.59008074388;5655.462731040545;5891.882162356815;6058.333583305833;6064.878702425926;847.4491580472619;911.9270458363314;914.2594498456272;914.9954250228707;957.2533423929347;3744.9063773405883;3772.5095737553943;3876.1472476055787;3946.625070618287;3949;10486.423246721504;10952.507137178687;10958.945205274082;10963.013328800445;10967.50646220922;10893.727656808644;10914.399708016135;10920.017198452177;10927.3989930553;10932.398718070583;7820.569868657165;8192.426530142951;8325.875111873327;8334.333253339839;8341.290990265774;5222.764975576032;5591.692456914746;5697.373288938296;5703.08750599902;5742.655440673573;9845.720056392624;9990;10101.494925253855;10238.846417303896;10353.275270730977;9988.350757200758;10290.02244786744;10377.429241391796;10414.895851041392;10434.217433692655;6408.743650254124;6452.612843229421;6886.10480637514;6992.3357281058215;7124.465665075244;8220.753377398687;8500.022497412876;8500.787480313042;8501.064882862756;8502.574871256536;7350.7427240046345;7485.962570187115;7568.243175682388;7626.771196864103;7708.152103268748;7208.503156716955;7324.853502930018;7358.96320518394;7510.023696919366;7562.962185189039;6879.174499060145;7020.964895175492;7024;7024.859502810017;7036.085308909809;4854.975725121351;4883.780229889592;4949.604031677529;4960.156773348467;5010.749462526932;3924.3132451820247;4822.807087716452;4886.3403440536495;4899.167141585867;4943.406791185081 18:33:59 INFO - PROCESS | 1649 | |4;prototype.html;294.68910299633893;301.22885413341845;309.3272133110484;313.59702781925233;315.6007650322341;4271.765052922092;4457.796394973355;5180.740962951847;5185.222216667502;5215.348081489814;4300.892477688059;4490.057088011522;4789.042191561684;4913.434954980178;5234.973825130869;3780.8487660493547;3971.26531591655;4003.7588347612186;4006.318925782624;4009.4587230723914;4012.970207493353;4018.77896715682;4030.7380020298733;4044.777537235451;4050.311447053993;3719.851205951759;3880.049387899954;3903.3169195390833;4010.9799451002705;4017.819198136091;3498.702610278142;3535.7524973251884;3683.0976410779263;3914.8042597870276;3941.881743547698;2989.626296712911;3375;3376.088456116859;3397.3375191837604;3414.3683418567643;4435.645148388154;4675.438947326343;5077.486644111152;5087.389513258396;5144.305518754958;3233.4826427771445;4063.6589925324865;4133.338665813455;4509.6392288616835;4531.728096314198;4813.566778989908;5052.69683818972;5494.09347457669;5508.4491550845;5509.063459211903;3413.2149605590603;3473.3574288756663;3584.910377240564;3730.2539492101687;3764.3600587900123;4986.90026199474;5218.773588206768;5570.080936645449;5785.710714464302;5857.092150716646;3192.7605429592872;3448.5516882805578;3793.2223894101644;3815.236952609489;3831.1954489556947;3369.5234003007768;3769.849206031728;3877.6316249956208;3890.2997460456822;3931;3779.981100094482;3792.905177370533;4236.9364459533135;4314.956850431456;4563.520830312769;4956.479569645207;5469;5566.248556444909;5654.116400635706;5655.632383895034;1202.9759404811855;1337.2912356451097;1338.4110991163857;1349.0489205110373;1349.7637913365122;685.6092027544252;746.6416120262196;802.59545794859;815.804206990318;818.145038434837;3840.8299310889256;3965.6490265725415;4286.89282767927;4295.198749107063;4348.847790327323;4202.004048077139;4327.7186982846015;4556.0432309214775;4714.38712967312;4801.663883528119;3984.7524267722292;4087.2029954159248;4121.876343709693;4154.335306350969;4325.610695037399;2122.1617461103087;2262.4230821140504;2383.7377888432256;2423.5395274897714;2479.9584174646693;4199.874003779831;4464.709793863389;4809.254565542346;4872.6832755870755;4901.803927842846;4857.781399837016;4861.927071093936;4944.722814765274;4971;5577.163425486209;5070.540787717954;5861.824145275648;5879.559033072451;5894.705264736832;5901.734421951022;5455.345358556999;5639.605227634191;5657.236273103243;5673.404292549223;5741.2536370273265;5039.6220283479615;5190.532852043334;5216.861086954331;5588.916166257591;5697.031504644137;5021.698698078127;5484.314460692413;5897.321807991964;5906.873525530719;5991.285403050137;2968.4359971605327;3629.7459177857295;3952.66402355803;3992.7604343739467;3998.560518213499;5380.950714610613;5745.6552606843725;5752.367239603724;5756.560859785054;5757.424257574209;4616.722996620214;5126.743662816919;5131.897362052813;5584.162375643524;5657;4869.6591238612955;5369.248305237348;5418.701971391612;5577.609567330248;5629.521490673339;5224.738763061908;5449.863753406196;5476.58925580591;5485.6434331768305;5524.088525393265;4643.953560464352;5566.4990150886615;5624.325080990144;5625.484072454716;5649.887002259849;5526.834194974157;5534.7621641340265;5535.806246781343;5563.443655634404;5614.24207731951 18:33:59 INFO - PROCESS | 1649 | |5;yui.html;709.9787006389809;721.9422446204304;772.74499415193;805.198827166969;812.4312980913362;15666.921665391672;16355.672886542277;16364.590885227875;16376.181190940464;16381.460873564587;14281.642958926031;14324.570262892104;14325.713485730279;14326.641833954156;14652.706945861077;592.8784599157184;695.151914664109;826.1160558202725;836.640244694781;861.8491763941316;836.8451836410253;850.8255807559466;851.93610479214;852.0499642898186;852.7271273192581;899.7210864631952;902.6975963052353;903.3405613901823;904.3940559824933;906.0305473143738;704.7674267491716;864.2740098317444;899.3704406915153;899.9730008099767;901.0718959471754;674.5143496682381;675.7364627795164;676.0096458688046;676.3844901139988;676.5061505101254;14769.33537990793;14777.187254700988;14784;14790.630234244123;14803.780922437054;13766.31168441574;13987.370568324348;14127.223002734845;14253.358598863077;14313.28433578317;12568.30874301913;12600.616415811368;12699.746005079938;12835.743285134246;13290;4440.289553671397;4486.842960496367;4517.186906356855;4634.0036892068365;4707.058588282357;118946.40526797397;121047.39476302562;121059;126987;127085.36457317747;10992.780144397067;11230.213885028134;11323.207375483636;11375.772484550263;11520.193586448868;22539.88730056339;22573.4356641082;22576.984035718433;22642.320730378116;22643.320700379016;7816.257455541714;7841.411894107965;7864.370850331959;7892.960535197402;7938.285554300141;3562.4834399012516;3584.7311451640708;3623.601403845575;3652.2878038782183;3674.375356189454;3348.715359194447;3406.710429613461;3419.4186988212064;3457.498662693946;3471.739619528495;651.680676468527;657.5134400543659;658.3350815676201;661.3419647450721;673.0812441018073;490.8576512811316;594.5006194796392;601.8134378342728;604.6553464525178;607.4259824465837;8870.86693699595;9121.31590130743;9123.908760912305;9131.81736365269;9165.266778657691;596.4075917300818;626.8025571944822;627.8336240896166;632.9019002054689;634.429013887505;4177.089537423033;4232.767197804151;4274.230638485103;4584.633229341644;4606.332081848181;149161;151989;153221.91169927;157596;158960.20519897557;130554.04168937473;133488;135019.32490337486;135697;135750;79377.20622793814;97786;98087.01912980921;105848.47075764571;111559;9385.953070234606;9677.129058384779;9802.215822734306;9898.802023959624;9963.651272205438;45773;49682.50317496779;51460;61980.38019619746;62818;63092;73967.63016184885;93607.25570977085;94022.05977940133;96235;11007.339559626447;12769.361531923552;13058;13127.60617181448;13668.31658417095;12338.951189149024;12404.317762523147;12571.245725256515;12650.367481625697;14008.929955350159;13581;14086.436542538184;14096.929515352358;14123.364448599632;14685.853141468877;11375.658730238107;13877.375518101911;14024.158550487004;14213.786793198316;14691.632709182357;13059.412326445523;13091.476340946636;13770.242636654679;14195.290235487977;14274.000819942505;11224.933631305013;11398.373089480157;11516.712082198012;11540.538378464767;11558.768824623292;8338.639076800782;9309.627614895327;11315.207935444769;11339.376334301693;11358.60244891425 18:33:59 INFO - PROCESS | 1649 | __end_tp_report 18:33:59 INFO - PROCESS | 1649 | __start_cc_report 18:33:59 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1405 18:33:59 INFO - PROCESS | 1649 | __end_cc_report 18:33:59 INFO - PROCESS | 1649 | __startTimestamp1472780039815__endTimestamp 18:33:59 INFO - PROCESS | 1649 | 18:33:59 INFO - PROCESS | 1649 | ------- Summary: start ------- 18:33:59 INFO - PROCESS | 1649 | Number of tests: 6 18:33:59 INFO - PROCESS | 1649 | 18:33:59 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5714.18 Median:5919.53 stddev:654.67 (11.1%) stddev-sans-first:629.16 18:33:59 INFO - PROCESS | 1649 | Values: 3320.0 3342.7 3345.2 3358.8 3409.9 5600.1 5646.2 5692.5 5737.9 5752.9 5683.7 5692.4 5715.0 5791.9 5793.9 5106.2 5117.5 5204.2 5218.2 5244.2 5210.4 5244.8 5259.2 5260.1 5300.9 5088.3 5119.8 5133.9 5189.9 5196.8 4709.6 5154.8 5239.8 5258.5 5269.5 5124.9 5158.8 5178.5 5193.0 5242.4 5567.2 5723.3 5730.4 5748.8 5759.2 5124.8 6131.9 6151.8 6205.8 6218.9 6211.7 6214.9 6229.7 6251.3 6283.3 5641.0 5699.1 5749.6 5761.1 5800.0 6230.1 6309.6 6323.3 6348.1 6353.7 6089.2 6099.1 6111.0 6115.6 6149.2 6103.2 6275.7 6276.0 6291.1 6292.6 4845.7 6103.2 6106.1 6133.3 6153.4 6265.8 6269.1 6275.8 6280.9 6283.7 4841.2 4923.2 4926.2 4934.6 4945.6 4347.0 4347.7 4362.7 4415.4 4449.6 5737.4 5737.6 5737.9 5738.5 5770.9 6307.8 6316.5 6320.6 6321.6 6345.3 5564.1 5623.5 5643.2 5656.3 5717.6 5352.9 5402.5 5403.7 5404.0 5409.6 5998.1 6262.8 6317.5 6334.9 6361.3 6332.1 6332.6 6337.3 6344.4 6364.7 6298.5 6312.4 6314.9 6322.7 6352.5 6111.1 6115.9 6146.1 6194.5 6216.5 5244.4 6167.9 6237.5 6283.3 6297.3 6114.1 6116.5 6136.9 6142.0 6147.3 5912.9 5969.7 6008.5 6049.1 6091.2 5974.7 5993.7 5998.2 6003.9 6035.1 6108.5 6125.7 6131.6 6132.8 6133.2 5110.9 5654.6 5670.5 5919.5 5956.8 18:33:59 INFO - PROCESS | 1649 | 18:33:59 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:52991.65 Median:351991.00 stddev:104085.93 (29.6%) stddev-sans-first:104303.19 18:33:59 INFO - PROCESS | 1649 | Values: 460.7 465.9 482.3 503.1 536.9 31532.5 32406.8 39905.6 41479.4 43583.1 42078.4 42534.0 42600.7 42626.6 42701.4 8753.5 9536.6 9727.8 9752.6 9764.9 11684.2 11925.6 11932.3 12282.4 12291.0 11888.8 11902.8 12003.9 12009.1 12029.9 8730.4 11672.3 11944.3 12001.0 12014.8 11478.9 11881.8 11884.8 11901.6 11916.4 40165.2 40492.6 40505.0 40526.8 40568.6 3494.6 3705.6 3749.9 4074.6 4162.4 30522.2 31141.4 31543.5 31655.5 31759.4 3280.3 3350.3 3353.9 3362.2 3399.4 351583.0 352399.0 352510.0 352685.0 358007.2 58881.0 59116.0 59195.7 59210.4 59226.0 62404.7 62436.4 62649.7 62678.7 62690.0 16107.4 16384.2 16384.9 16412.5 16420.7 11468.4 11635.9 12277.5 12655.1 12789.0 16208.6 16947.2 16985.2 17005.6 17025.7 4764.8 5122.5 6121.5 6149.4 6153.4 832.8 863.0 990.6 1067.0 1109.8 29158.3 29284.4 29769.6 29949.0 30154.0 942.8 1036.4 1084.3 1110.4 1156.0 14440.9 14457.2 15139.8 15256.4 15766.1 150518.0 183804.0 184331.0 184598.0 184751.0 45209.8 56518.7 56667.4 56671.1 56731.0 1276.3 1327.7 1333.0 1337.5 1339.3 27971.0 28812.9 29458.3 29528.9 30198.2 50056.2 50266.7 50327.0 50339.2 50394.5 543898.0 548366.0 548674.0 549653.0 549755.0 31385.8 34124.5 39913.6 40427.6 40993.6 46296.6 46314.5 46393.5 46671.8 47063.0 37530.2 38137.2 38236.8 39149.0 39823.6 37159.0 38205.8 38208.0 39329.6 39488.0 37231.0 38056.4 38387.6 38533.8 38587.2 30374.7 33969.8 33973.8 34064.7 34111.3 24014.9 25170.7 25622.9 25725.7 25823.5 18:33:59 INFO - PROCESS | 1649 | 18:33:59 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:32908.20 Median:1719.43 stddev:92165.53 (5360.2%) stddev-sans-first:92391.66 18:33:59 INFO - PROCESS | 1649 | Values: 355.1 368.5 373.6 387.1 407.4 15209.0 19263.7 19514.1 20229.2 20493.8 19104.6 20318.0 20338.5 20523.4 20801.0 5853.6 6210.1 6212.9 6215.1 6216.4 5344.3 6159.9 6229.1 6232.3 6251.2 5940.6 6049.9 6185.9 6198.3 6205.2 5128.5 6065.1 6080.4 6135.5 6136.5 5043.3 5267.6 5295.2 5303.5 5323.8 25211.1 25438.5 25438.7 25496.4 25526.5 2264.3 2272.9 2395.1 3015.1 3083.8 12127.5 14334.6 15362.3 15496.2 15997.6 1832.3 1833.3 1835.5 1843.8 1852.5 118365.0 120228.0 121456.0 123361.4 123656.4 9039.2 10282.5 10489.1 10853.8 10867.5 13484.0 13517.4 13538.7 13551.8 13563.0 8234.4 8493.8 8501.0 8641.4 8658.7 6771.0 8526.3 8534.5 8657.1 8727.7 5331.5 6001.5 6557.2 6580.4 6677.3 1668.0 1769.2 1842.5 1869.5 1888.7 845.4 882.1 896.4 899.8 940.2 20298.0 21253.5 21703.8 21743.8 21818.7 939.7 943.8 965.4 983.4 989.3 11011.0 11460.6 11618.2 11640.8 11697.8 516972.0 555109.0 559341.0 567319.0 567621.0 81926.0 102880.0 107238.0 108043.9 110425.4 987.7 987.7 989.4 991.3 1002.2 18602.6 18890.0 19073.0 19098.6 19100.4 37269.3 51529.7 51646.2 52287.5 52416.5 55967.0 70566.0 70971.0 73171.0 77019.6 6117.9 6486.3 7755.3 8018.8 8221.9 12518.7 12931.9 13322.7 13442.2 13445.5 9183.3 9940.8 10485.9 10629.6 11199.4 10589.7 11013.9 11305.6 11477.1 11828.0 11354.8 11368.7 11696.6 11924.4 12084.2 7899.7 7989.7 8122.2 8235.0 8441.2 8258.3 10636.9 11511.8 11596.9 11652.4 18:33:59 INFO - PROCESS | 1649 | 18:33:59 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5667.39 Median:4912.76 stddev:3218.12 (65.5%) stddev-sans-first:3210.78 18:33:59 INFO - PROCESS | 1649 | Values: 1348.3 1495.3 1531.8 1724.5 1911.7 7781.5 8405.6 8848.6 8858.9 8953.6 8722.3 8813.6 8814.0 8823.8 8828.0 2352.4 2374.9 2384.3 2387.1 2387.4 2332.9 2361.5 2379.6 2384.0 2385.6 2347.2 2360.2 2370.8 2380.1 2380.9 2321.2 2344.2 2354.1 2354.5 2369.2 2304.4 2337.1 2341.9 2342.2 2348.5 8663.5 8750.9 8853.0 8865.5 8881.4 5226.6 5419.2 5423.9 5425.6 5436.4 3633.9 3645.1 3703.6 3710.9 3717.5 913.2 1079.6 1088.0 1100.4 1103.7 11592.7 11642.7 11695.6 11728.6 11734.4 4378.5 4506.1 4619.1 4629.2 4631.4 9148.2 9149.6 9151.9 9158.3 9167.3 5248.4 5250.5 5257.1 5258.6 5259.1 4376.2 4523.7 4758.7 4875.0 4926.4 3208.9 3671.3 3711.6 3780.2 3812.2 1157.2 1170.5 1172.8 1180.1 1182.0 739.7 775.1 832.2 885.2 885.8 5465.6 5655.5 5891.9 6058.3 6064.9 847.4 911.9 914.3 915.0 957.3 3744.9 3772.5 3876.1 3946.6 3949.0 10486.4 10952.5 10958.9 10963.0 10967.5 10893.7 10914.4 10920.0 10927.4 10932.4 7820.6 8192.4 8325.9 8334.3 8341.3 5222.8 5591.7 5697.4 5703.1 5742.7 9845.7 9990.0 10101.5 10238.8 10353.3 9988.4 10290.0 10377.4 10414.9 10434.2 6408.7 6452.6 6886.1 6992.3 7124.5 8220.8 8500.0 8500.8 8501.1 8502.6 7350.7 7486.0 7568.2 7626.8 7708.2 7208.5 7324.9 7359.0 7510.0 7563.0 6879.2 7021.0 7024.0 7024.9 7036.1 4855.0 4883.8 4949.6 4960.2 5010.7 3924.3 4822.8 4886.3 4899.2 4943.4 18:33:59 INFO - PROCESS | 1649 | 18:33:59 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4300.50 Median:4630.34 stddev:1366.62 (29.5%) stddev-sans-first:1336.97 18:33:59 INFO - PROCESS | 1649 | Values: 294.7 301.2 309.3 313.6 315.6 4271.8 4457.8 5180.7 5185.2 5215.3 4300.9 4490.1 4789.0 4913.4 5235.0 3780.8 3971.3 4003.8 4006.3 4009.5 4013.0 4018.8 4030.7 4044.8 4050.3 3719.9 3880.0 3903.3 4011.0 4017.8 3498.7 3535.8 3683.1 3914.8 3941.9 2989.6 3375.0 3376.1 3397.3 3414.4 4435.6 4675.4 5077.5 5087.4 5144.3 3233.5 4063.7 4133.3 4509.6 4531.7 4813.6 5052.7 5494.1 5508.4 5509.1 3413.2 3473.4 3584.9 3730.3 3764.4 4986.9 5218.8 5570.1 5785.7 5857.1 3192.8 3448.6 3793.2 3815.2 3831.2 3369.5 3769.8 3877.6 3890.3 3931.0 3780.0 3792.9 4236.9 4315.0 4563.5 4956.5 5469.0 5566.2 5654.1 5655.6 1203.0 1337.3 1338.4 1349.0 1349.8 685.6 746.6 802.6 815.8 818.1 3840.8 3965.6 4286.9 4295.2 4348.8 4202.0 4327.7 4556.0 4714.4 4801.7 3984.8 4087.2 4121.9 4154.3 4325.6 2122.2 2262.4 2383.7 2423.5 2480.0 4199.9 4464.7 4809.3 4872.7 4901.8 4857.8 4861.9 4944.7 4971.0 5577.2 5070.5 5861.8 5879.6 5894.7 5901.7 5455.3 5639.6 5657.2 5673.4 5741.3 5039.6 5190.5 5216.9 5588.9 5697.0 5021.7 5484.3 5897.3 5906.9 5991.3 2968.4 3629.7 3952.7 3992.8 3998.6 5381.0 5745.7 5752.4 5756.6 5757.4 4616.7 5126.7 5131.9 5584.2 5657.0 4869.7 5369.2 5418.7 5577.6 5629.5 5224.7 5449.9 5476.6 5485.6 5524.1 4644.0 5566.5 5624.3 5625.5 5649.9 5526.8 5534.8 5535.8 5563.4 5614.2 18:33:59 INFO - PROCESS | 1649 | 18:33:59 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:24794.83 Median:3573.61 stddev:40256.23 (1126.5%) stddev-sans-first:40328.54 18:33:59 INFO - PROCESS | 1649 | Values: 710.0 721.9 772.7 805.2 812.4 15666.9 16355.7 16364.6 16376.2 16381.5 14281.6 14324.6 14325.7 14326.6 14652.7 592.9 695.2 826.1 836.6 861.8 836.8 850.8 851.9 852.0 852.7 899.7 902.7 903.3 904.4 906.0 704.8 864.3 899.4 900.0 901.1 674.5 675.7 676.0 676.4 676.5 14769.3 14777.2 14784.0 14790.6 14803.8 13766.3 13987.4 14127.2 14253.4 14313.3 12568.3 12600.6 12699.7 12835.7 13290.0 4440.3 4486.8 4517.2 4634.0 4707.1 118946.4 121047.4 121059.0 126987.0 127085.4 10992.8 11230.2 11323.2 11375.8 11520.2 22539.9 22573.4 22577.0 22642.3 22643.3 7816.3 7841.4 7864.4 7893.0 7938.3 3562.5 3584.7 3623.6 3652.3 3674.4 3348.7 3406.7 3419.4 3457.5 3471.7 651.7 657.5 658.3 661.3 673.1 490.9 594.5 601.8 604.7 607.4 8870.9 9121.3 9123.9 9131.8 9165.3 596.4 626.8 627.8 632.9 634.4 4177.1 4232.8 4274.2 4584.6 4606.3 149161.0 151989.0 153221.9 157596.0 158960.2 130554.0 133488.0 135019.3 135697.0 135750.0 79377.2 97786.0 98087.0 105848.5 111559.0 9386.0 9677.1 9802.2 9898.8 9963.7 45773.0 49682.5 51460.0 61980.4 62818.0 63092.0 73967.6 93607.3 94022.1 96235.0 11007.3 12769.4 13058.0 13127.6 13668.3 12339.0 12404.3 12571.2 12650.4 14008.9 13581.0 14086.4 14096.9 14123.4 14685.9 11375.7 13877.4 14024.2 14213.8 14691.6 13059.4 13091.5 13770.2 14195.3 14274.0 11224.9 11398.4 11516.7 11540.5 11558.8 8338.6 9309.6 11315.2 11339.4 11358.6 18:33:59 INFO - PROCESS | 1649 | -------- Summary: end -------- 18:33:59 INFO - PROCESS | 1649 | 18:34:00 INFO - TEST-INFO | 1649: exit 0 18:34:00 INFO - TEST-OK | dromaeo_css | took 1117382ms 18:34:00 INFO - TEST-START | kraken 18:34:00 INFO - operating with platform_type : mac_ 18:34:00 INFO - Initialising browser for kraken test... 18:34:00 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpzpNjU1/profile http://localhost:49274/getInfo.html) 18:34:02 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 18:34:02 INFO - PROCESS | 1659 | colorDepth:24 18:34:02 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 18:34:02 INFO - PROCESS | 1659 | __metrics 18:34:02 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:34:02 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:34:03 INFO - TEST-INFO | 1659: exit 0 18:34:03 INFO - Browser initialized. 18:34:03 INFO - Running cycle 1/1 for kraken test... 18:34:03 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpzpNjU1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:34:15 INFO - PROCESS | 1660 | RSS: Main: 233746432 18:34:15 INFO - PROCESS | 1660 | 18:34:18 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 18:34:19 INFO - PROCESS | 1660 | RSS: Main: 293408768 18:34:19 INFO - PROCESS | 1660 | 18:34:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 18:34:22 INFO - PROCESS | 1660 | RSS: Main: 289374208 18:34:22 INFO - PROCESS | 1660 | 18:34:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 18:34:26 INFO - PROCESS | 1660 | RSS: Main: 383021056 18:34:26 INFO - PROCESS | 1660 | 18:34:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 18:34:29 INFO - PROCESS | 1660 | RSS: Main: 283656192 18:34:29 INFO - PROCESS | 1660 | 18:34:31 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:34:32 INFO - PROCESS | 1660 | RSS: Main: 287600640 18:34:32 INFO - PROCESS | 1660 | 18:34:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 18:34:35 INFO - PROCESS | 1660 | RSS: Main: 325337088 18:34:35 INFO - PROCESS | 1660 | 18:34:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 18:34:38 INFO - PROCESS | 1660 | RSS: Main: 325455872 18:34:38 INFO - PROCESS | 1660 | 18:34:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 18:34:42 INFO - PROCESS | 1660 | RSS: Main: 325246976 18:34:42 INFO - PROCESS | 1660 | 18:34:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:34:44 INFO - PROCESS | 1660 | RSS: Main: 298766336 18:34:44 INFO - PROCESS | 1660 | 18:34:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:34:46 INFO - PROCESS | 1660 | RSS: Main: 304369664 18:34:46 INFO - PROCESS | 1660 | 18:34:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:34:49 INFO - PROCESS | 1660 | RSS: Main: 304054272 18:34:49 INFO - PROCESS | 1660 | 18:34:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:34:53 INFO - PROCESS | 1660 | RSS: Main: 319885312 18:34:53 INFO - PROCESS | 1660 | 18:34:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:34:56 INFO - PROCESS | 1660 | RSS: Main: 316022784 18:34:56 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 18:34:58 INFO - PROCESS | 1660 | RSS: Main: 359313408 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | __start_tp_report 18:34:58 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 18:34:58 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 18:34:58 INFO - PROCESS | 1660 | |i|pagename|runs| 18:34:58 INFO - PROCESS | 1660 | |0;ai-astar;112;113;115;113;112;112;112;113;112;123 18:34:58 INFO - PROCESS | 1660 | |1;audio-beat-detection;146;115;130;159;109;129;128;107;160;108 18:34:58 INFO - PROCESS | 1660 | |2;audio-dft;185;183;184;180;183;181;182;182;183;182 18:34:58 INFO - PROCESS | 1660 | |3;audio-fft;79;74;76;155;71;80;81;84;141;53 18:34:58 INFO - PROCESS | 1660 | |4;audio-oscillator;83;88;136;71;70;75;121;69;69;121 18:34:58 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;87;84;89;85;88;85;93;86;89;85 18:34:58 INFO - PROCESS | 1660 | |6;imaging-darkroom;95;97;97;97;97;94;96;96;96;95 18:34:58 INFO - PROCESS | 1660 | |7;imaging-desaturate;84;85;86;91;86;84;86;86;86;85 18:34:58 INFO - PROCESS | 1660 | |8;json-parse-financial;54;52;53;54;52;54;53;54;53;54 18:34:58 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;67;67;76;76;73;68;68;71;71;73 18:34:58 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;86;88;88;85;85;87;83;86;88;85 18:34:58 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;138;143;147;146;141;138;139;151;194;150 18:34:58 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;169;166;168;167;168;168;166;167;170;166 18:34:58 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;60;81;82;87;68;66;67;66;66;67 18:34:58 INFO - PROCESS | 1660 | __end_tp_report 18:34:58 INFO - PROCESS | 1660 | __start_cc_report 18:34:58 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2001 18:34:58 INFO - PROCESS | 1660 | __end_cc_report 18:34:58 INFO - PROCESS | 1660 | __startTimestamp1472780098939__endTimestamp 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | ------- Summary: start ------- 18:34:58 INFO - PROCESS | 1660 | Number of tests: 14 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:113.70 Median:113.00 stddev:3.40 (3.0%) stddev-sans-first:3.55 18:34:58 INFO - PROCESS | 1660 | Values: 112.0 113.0 115.0 113.0 112.0 112.0 112.0 113.0 112.0 123.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:129.50 stddev:20.20 (15.6%) stddev-sans-first:20.48 18:34:58 INFO - PROCESS | 1660 | Values: 146.0 115.0 130.0 159.0 109.0 129.0 128.0 107.0 160.0 108.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:1.43 (0.8%) stddev-sans-first:1.20 18:34:58 INFO - PROCESS | 1660 | Values: 185.0 183.0 184.0 180.0 183.0 181.0 182.0 182.0 183.0 182.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:89.40 Median:77.50 stddev:32.22 (41.6%) stddev-sans-first:33.95 18:34:58 INFO - PROCESS | 1660 | Values: 79.0 74.0 76.0 155.0 71.0 80.0 81.0 84.0 141.0 53.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:70.50 stddev:25.73 (36.5%) stddev-sans-first:27.15 18:34:58 INFO - PROCESS | 1660 | Values: 83.0 88.0 136.0 71.0 70.0 75.0 121.0 69.0 69.0 121.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:87.10 Median:87.50 stddev:2.73 (3.1%) stddev-sans-first:2.89 18:34:58 INFO - PROCESS | 1660 | Values: 87.0 84.0 89.0 85.0 88.0 85.0 93.0 86.0 89.0 85.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.50 stddev:1.05 (1.1%) stddev-sans-first:1.05 18:34:58 INFO - PROCESS | 1660 | Values: 95.0 97.0 97.0 97.0 97.0 94.0 96.0 96.0 96.0 95.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:1.97 (2.3%) stddev-sans-first:1.96 18:34:58 INFO - PROCESS | 1660 | Values: 84.0 85.0 86.0 91.0 86.0 84.0 86.0 86.0 86.0 85.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.83 18:34:58 INFO - PROCESS | 1660 | Values: 54.0 52.0 53.0 54.0 52.0 54.0 53.0 54.0 53.0 54.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:72.00 stddev:3.46 (4.8%) stddev-sans-first:3.36 18:34:58 INFO - PROCESS | 1660 | Values: 67.0 67.0 76.0 76.0 73.0 68.0 68.0 71.0 71.0 73.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:86.50 stddev:1.66 (1.9%) stddev-sans-first:1.76 18:34:58 INFO - PROCESS | 1660 | Values: 86.0 88.0 88.0 85.0 85.0 87.0 83.0 86.0 88.0 85.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:146.50 stddev:16.61 (11.3%) stddev-sans-first:17.16 18:34:58 INFO - PROCESS | 1660 | Values: 138.0 143.0 147.0 146.0 141.0 138.0 139.0 151.0 194.0 150.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:168.00 stddev:1.35 (0.8%) stddev-sans-first:1.32 18:34:58 INFO - PROCESS | 1660 | Values: 169.0 166.0 168.0 167.0 168.0 168.0 166.0 167.0 170.0 166.0 18:34:58 INFO - PROCESS | 1660 | 18:34:58 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:67.50 stddev:8.91 (13.2%) stddev-sans-first:8.51 18:34:58 INFO - PROCESS | 1660 | Values: 60.0 81.0 82.0 87.0 68.0 66.0 67.0 66.0 66.0 67.0 18:34:58 INFO - PROCESS | 1660 | -------- Summary: end -------- 18:34:58 INFO - PROCESS | 1660 | 18:34:59 INFO - TEST-INFO | 1660: exit 0 18:34:59 INFO - TEST-OK | kraken | took 59361ms 18:34:59 INFO - SUITE-END | took 1176s 18:35:00 INFO - Completed test suite (00:19:37) 18:35:00 INFO - javascript benchmark 18:35:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3319.983400083, 3342.7492938029645, 3345.2138747394356, 3358.781679190851, 3409.9147521311966, 5600.103983362663, 5646.209530665705, 5692.544596432286, 5737.885242295151, 5752.942470575298, 5683.658980461165, 5692.402297758737, 5715.0, 5791.855203619901, 5793.855153621162, 5106.234064890277, 5117.513836185556, 5204.167333226685, 5218.243354713564, 5244.187150991603, 5210.400803907562, 5244.79020839166, 5259.237410575464, 5260.079486089938, 5300.946990530103, 5088.287639730441, 5119.820806271762, 5133.871653208662, 5189.922151167735, 5196.844094677166, 4709.62323014158, 5154.819581314673, 5239.764210610531, 5258.474152584749, 5269.473052694738, 5124.948750512484, 5158.767855446514, 5178.533931946143, 5193.0, 5242.397124330712, 5567.220589117486, 5723.255976723041, 5730.36965933747, 5748.798792042258, 5759.222504961818, 5124.795008199668, 6131.938680613226, 6151.784687535959, 6205.8138255851945, 6218.906716399258, 6211.689415529206, 6214.875702485971, 6229.719662615194, 6251.3123556408755, 6283.277423096357, 5640.971795140998, 5699.059655156852, 5749.568782341341, 5761.135829625589, 5800.042992906166, 6230.096635987847, 6309.558330916791, 6323.30443651198, 6348.143000694847, 6353.714082866282, 6089.238845144357, 6099.085137229363, 6111.022236442148, 6115.633062016204, 6149.169862068564, 6103.23709536308, 6275.6548389839, 6276.0, 6291.087792270096, 6292.590981586182, 4845.685030473008, 6103.186149163621, 6106.096658337741, 6133.334663918993, 6153.384661533811, 6265.8120256392385, 6269.059641053878, 6275.780347687809, 6280.937190628127, 6283.6858157091965, 4841.15279826029, 4923.212286034217, 4926.187179115406, 4934.629902757308, 4945.554900059012, 4347.021920067959, 4347.739135651871, 4362.672799539983, 4415.381846541487, 4449.555044495589, 5737.3975732548315, 5737.627054241461, 5737.8565535861935, 5738.454846789548, 5770.913436298459, 6307.779227727007, 6316.494680425555, 6320.5891617044745, 6321.589096708699, 6345.333739957238, 5564.054110801174, 5623.465770751772, 5643.238162847963, 5656.264685590847, 5717.571182161355, 5352.892942141136, 5402.540784033323, 5403.70279634616, 5404.0, 5409.5672346213, 5998.070299103646, 6262.780802671976, 6317.526185536011, 6334.9366506334345, 6361.332060133805, 6332.0818481320875, 6332.556721029484, 6337.302896681269, 6344.429001389897, 6364.681765911779, 6298.527610429328, 6312.43188113072, 6314.905276420765, 6322.715477803418, 6352.523560733056, 6111.135969295072, 6115.8776824462375, 6146.139540464428, 6194.5354098443695, 6216.53375996793, 5244.449332819999, 6167.876642467215, 6237.469815065589, 6283.308836027942, 6297.307296197323, 6114.113453549299, 6116.480099191618, 6136.938630613637, 6142.04798256271, 6147.323794382703, 5912.911306330322, 5969.671668058299, 6008.519318454426, 6049.062395328731, 6091.238595175603, 5974.6713930732485, 5993.67034813072, 5998.220231370068, 6003.939960600338, 6035.094735789667, 6108.450239478469, 6125.663088529996, 6131.601445906002, 6132.78535251264, 6133.172021777003, 5110.897782044412, 5654.6324488908085, 5670.461306175906, 5919.526437885045, 5956.821295361146], "value": 5672.456220349767, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.72586810847537, 465.8672278400656, 482.25973131243546, 503.11451844753225, 536.8738346488577, 31532.527012094808, 32406.837965810166, 39905.600943990554, 41479.37780933289, 43583.128337433234, 42078.36882446766, 42534.0, 42600.721978340625, 42626.573734262645, 42701.35947960775, 8753.518556479392, 9536.618535258582, 9727.75680607987, 9752.561134748918, 9764.853527197098, 11684.240524365932, 11925.642230733049, 11932.284062956207, 12282.385880705973, 12291.0, 11888.7622247555, 11902.821457678141, 12003.939980300087, 12009.099317551176, 12029.879701203006, 8730.388872778907, 11672.299662020307, 11944.283342999404, 12001.0, 12014.819777703298, 11478.885211147865, 11881.762364752656, 11884.762304753856, 11901.642950711492, 11916.404179790976, 40165.19669606621, 40492.59507404947, 40505.0, 40526.79736601298, 40568.594314057074, 3494.598121216042, 3705.6479634434686, 3749.850005999757, 4074.5925407459017, 4162.354835000579, 30522.236944076354, 31141.377172456425, 31543.526847097313, 31655.525167122512, 31759.39488774115, 3280.327532855783, 3350.27968986669, 3353.8764513887877, 3362.2098806780386, 3399.4390925497264, 351583.0, 352399.0, 352510.0, 352685.0, 358007.20996395114, 58881.0, 59116.0, 59195.704021479614, 59210.40789592135, 59226.0, 62404.68797655983, 62436.37563624397, 62649.68675156595, 62678.686606566676, 62690.0, 16107.355705771637, 16384.180790960403, 16384.934979226313, 16412.507624771275, 16420.671586568442, 11468.36923969173, 11635.88364116365, 12277.483115961206, 12655.050871184514, 12789.040821938206, 16208.594785130466, 16947.152642367833, 16985.23566439513, 17005.57486062858, 17025.659486810444, 4764.767482449711, 5122.487751224922, 6121.540884433685, 6149.354317796657, 6153.384661533811, 832.8292699996487, 862.9827403451895, 990.5933574324476, 1066.9786604267872, 1109.8446217529552, 29158.271043223664, 29284.414311714074, 29769.553456698166, 29948.95178668736, 30154.0, 942.7548837302351, 1036.43514284715, 1084.2789544952518, 1110.4342876544033, 1155.9595414160465, 14440.9169312302, 14457.204853733145, 15139.772903406238, 15256.38974441032, 15766.132862692662, 150518.0, 183804.0, 184331.0, 184598.0, 184751.0, 45209.77395113069, 56518.717406413525, 56667.433325666214, 56671.14993275104, 56731.0, 1276.3235485192863, 1327.6946302350514, 1333.0402110480827, 1337.498438085718, 1339.3035621476777, 27971.0, 28812.855935721025, 29458.263543411587, 29528.85235573894, 30198.245043874078, 50056.24803132019, 50266.74866625643, 50326.99346013132, 50339.204207431525, 50394.49605503898, 543898.0, 548366.0, 548674.0, 549653.0, 549755.0, 31385.8430707845, 34124.488132678525, 39913.600863990985, 40427.59572404238, 40993.59006409898, 46296.60182282678, 46314.53685463102, 46393.53606463892, 46671.833204170165, 47063.0, 37530.24939501249, 38137.23725525529, 38236.808815955745, 39149.0, 39823.60176398199, 37159.0, 38205.80897095497, 38208.0, 39329.60670393259, 39488.0, 37231.04303311457, 38056.42915356217, 38387.6161238384, 38533.843984680505, 38587.22825543417, 30374.696253037186, 33969.830150849084, 33973.83013084919, 34064.65935340615, 34111.317773644885, 24014.919328630604, 25170.74829251684, 25622.871885640452, 25725.742742573082, 25823.483530329664], "value": 19397.41982895759, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.1033640058853, 368.51724241243954, 373.60957799099947, 387.0864759168362, 407.3828150352217, 15209.0, 19263.71104433433, 19514.121864516113, 20229.190832366687, 20493.795062049376, 19104.617907641838, 20318.0, 20338.49153771153, 20523.38429847103, 20801.0, 5853.648781073139, 6210.130581718563, 6212.906806397885, 6215.067739839015, 6216.37836216377, 5344.251804747339, 6159.8768024639485, 6229.127922090888, 6232.314445411024, 6251.187345645059, 5940.643561386309, 6049.909251361233, 6185.907211391833, 6198.256209254873, 6205.22434695663, 5128.53843154116, 6065.059915713049, 6080.391960803929, 6135.5398345124295, 6136.509079273647, 5043.344365232533, 5267.631265811395, 5295.179247216649, 5303.5226829585135, 5323.760430780624, 25211.11761088353, 25438.491230175292, 25438.745612543822, 25496.36259093519, 25526.489470210676, 2264.3433404312727, 2272.8863556822257, 2395.0659242895285, 3015.0954713586057, 3083.753299736016, 12127.514899404014, 14334.638209370198, 15362.308696108701, 15496.225188740518, 15997.600059998476, 1832.2579355360967, 1833.275856036858, 1835.5312105218459, 1843.815618438159, 1852.4905650945964, 118365.0, 120228.0, 121456.0, 123361.38319308436, 123656.38171809174, 9039.23166530839, 10282.485875706185, 10489.108425783894, 10853.782924341469, 10867.510962006729, 13484.0, 13517.391717372742, 13538.729225415436, 13551.796723049161, 13563.0, 8234.423590348619, 8493.830123397622, 8501.0, 8641.351898607629, 8658.653653853902, 6770.966145169243, 8526.314211023508, 8534.530600817016, 8657.091005444463, 8727.690846373094, 5331.466853314638, 6001.489873360813, 6557.2131344239, 6580.407763301326, 6677.265500794909, 1667.9833201667827, 1769.195016267595, 1842.4933143385408, 1869.5139263791511, 1888.6600411925986, 845.446232717571, 882.0650110882485, 896.4173287363136, 899.8155378147464, 940.1632547033009, 20298.0, 21253.46866328354, 21703.782962170175, 21743.782562174492, 21818.672719909213, 939.7415710679481, 943.7970836270235, 965.3773316211004, 983.4492684096929, 989.2877128467492, 11011.0, 11460.598879039193, 11618.244814087057, 11640.825387619192, 11697.76604467906, 516972.0, 555109.0, 559341.0, 567319.0, 567621.0, 81926.0, 102880.0, 107238.0, 108043.91956080339, 110425.44787276012, 987.6641941739842, 987.6987518806833, 989.3865803202061, 991.2912267728609, 1002.2132625888643, 18602.627947440706, 18890.0, 19073.0, 19098.618027639648, 19100.421443105002, 37269.25461490701, 51529.742351288005, 51646.225306621185, 52287.47712522826, 52416.47583524269, 55967.0, 70566.0, 70971.0, 73171.0, 77019.61490192513, 6117.908231376444, 6486.254080780582, 7755.340796032175, 8018.759437216893, 8221.917780822116, 12518.749625007631, 12931.870681293067, 13322.733545329233, 13442.193468391928, 13445.46218151263, 9183.311251656287, 9940.751481213027, 10485.895141048797, 10629.627963021258, 11199.384033878216, 10589.682309530726, 11013.944930275618, 11305.604303849326, 11477.08183345347, 11827.994620457357, 11354.829677554679, 11368.715782105513, 11696.649100526998, 11924.403779811148, 12084.154109212272, 7899.68401263943, 7989.680412783424, 8122.187781221831, 8234.958825205835, 8441.198086181803, 8258.339332853477, 10636.8936310639, 11511.848815118421, 11596.884031159918, 11652.417379131179], "value": 9579.111565932168, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.2854087333717, 1495.3495229575135, 1531.8238402583702, 1724.5429961060322, 1911.6654585447543, 7781.455298129134, 8405.579721013948, 8848.601812918418, 8858.867116993242, 8953.552322383888, 8722.258608018326, 8813.559322033905, 8814.0, 8823.823523529525, 8828.0, 2352.364861487402, 2374.881255937205, 2384.3443053160345, 2387.092904696213, 2387.379281386843, 2332.9416764580938, 2361.456864921069, 2379.607364784817, 2384.0463814474174, 2385.642153676945, 2347.225415612852, 2360.209329874494, 2370.751071137523, 2380.071772008918, 2380.880955952204, 2321.1759825262125, 2344.19125401737, 2354.105439932832, 2354.540864531417, 2369.206315884181, 2304.4354133237466, 2337.146941366406, 2341.9297421077395, 2342.1802369170823, 2348.5302939412013, 8663.523506207157, 8750.86873696895, 8852.955735221347, 8865.468071915706, 8881.378303518757, 5226.608004399685, 5419.241306217133, 5423.891522169574, 5425.6202065855405, 5436.401995780461, 3633.9273214535824, 3645.070507020719, 3703.574088979775, 3710.9072273193387, 3717.4795528626014, 913.2328843771264, 1079.578964203961, 1088.0370871778455, 1100.3672888089382, 1103.6633826682857, 11592.6522204334, 11642.6507204784, 11695.590654327058, 11728.648140555797, 11734.354610496504, 4378.496472905656, 4506.121306345297, 4619.129252652681, 4629.166749984967, 4631.421072365954, 9148.17666410013, 9149.63401463934, 9151.90848091524, 9158.313126515539, 9167.312451566158, 5248.422673505911, 5250.501202385767, 5257.132573125468, 5258.631895767336, 5259.105951988095, 4376.168527979674, 4523.705959112647, 4758.738269395148, 4875.049365373789, 4926.359573255454, 3208.871645134215, 3671.2657468506404, 3711.554613446404, 3780.2061567071223, 3812.2375524895133, 1157.2362240920952, 1170.4849866058908, 1172.8358029875824, 1180.0677464802882, 1181.988180118205, 739.6523633892062, 775.0892701076212, 832.2094010689868, 885.2165833237631, 885.822835432916, 5465.59008074388, 5655.462731040545, 5891.882162356815, 6058.333583305833, 6064.878702425926, 847.4491580472619, 911.9270458363314, 914.2594498456272, 914.9954250228707, 957.2533423929347, 3744.9063773405883, 3772.5095737553943, 3876.1472476055787, 3946.625070618287, 3949.0, 10486.423246721504, 10952.507137178687, 10958.945205274082, 10963.013328800445, 10967.50646220922, 10893.727656808644, 10914.399708016135, 10920.017198452177, 10927.3989930553, 10932.398718070583, 7820.569868657165, 8192.426530142951, 8325.875111873327, 8334.333253339839, 8341.290990265774, 5222.764975576032, 5591.692456914746, 5697.373288938296, 5703.08750599902, 5742.655440673573, 9845.720056392624, 9990.0, 10101.494925253855, 10238.846417303896, 10353.275270730977, 9988.350757200758, 10290.02244786744, 10377.429241391796, 10414.895851041392, 10434.217433692655, 6408.743650254124, 6452.612843229421, 6886.10480637514, 6992.3357281058215, 7124.465665075244, 8220.753377398687, 8500.022497412876, 8500.787480313042, 8501.064882862756, 8502.574871256536, 7350.7427240046345, 7485.962570187115, 7568.243175682388, 7626.771196864103, 7708.152103268748, 7208.503156716955, 7324.853502930018, 7358.96320518394, 7510.023696919366, 7562.962185189039, 6879.174499060145, 7020.964895175492, 7024.0, 7024.859502810017, 7036.085308909809, 4854.975725121351, 4883.780229889592, 4949.604031677529, 4960.156773348467, 5010.749462526932, 3924.3132451820247, 4822.807087716452, 4886.3403440536495, 4899.167141585867, 4943.406791185081], "value": 4528.866867570489, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.68910299633893, 301.22885413341845, 309.3272133110484, 313.59702781925233, 315.6007650322341, 4271.765052922092, 4457.796394973355, 5180.740962951847, 5185.222216667502, 5215.348081489814, 4300.892477688059, 4490.057088011522, 4789.042191561684, 4913.434954980178, 5234.973825130869, 3780.8487660493547, 3971.26531591655, 4003.7588347612186, 4006.318925782624, 4009.4587230723914, 4012.970207493353, 4018.77896715682, 4030.7380020298733, 4044.777537235451, 4050.311447053993, 3719.851205951759, 3880.049387899954, 3903.3169195390833, 4010.9799451002705, 4017.819198136091, 3498.702610278142, 3535.7524973251884, 3683.0976410779263, 3914.8042597870276, 3941.881743547698, 2989.626296712911, 3375.0, 3376.088456116859, 3397.3375191837604, 3414.3683418567643, 4435.645148388154, 4675.438947326343, 5077.486644111152, 5087.389513258396, 5144.305518754958, 3233.4826427771445, 4063.6589925324865, 4133.338665813455, 4509.6392288616835, 4531.728096314198, 4813.566778989908, 5052.69683818972, 5494.09347457669, 5508.4491550845, 5509.063459211903, 3413.2149605590603, 3473.3574288756663, 3584.910377240564, 3730.2539492101687, 3764.3600587900123, 4986.90026199474, 5218.773588206768, 5570.080936645449, 5785.710714464302, 5857.092150716646, 3192.7605429592872, 3448.5516882805578, 3793.2223894101644, 3815.236952609489, 3831.1954489556947, 3369.5234003007768, 3769.849206031728, 3877.6316249956208, 3890.2997460456822, 3931.0, 3779.981100094482, 3792.905177370533, 4236.9364459533135, 4314.956850431456, 4563.520830312769, 4956.479569645207, 5469.0, 5566.248556444909, 5654.116400635706, 5655.632383895034, 1202.9759404811855, 1337.2912356451097, 1338.4110991163857, 1349.0489205110373, 1349.7637913365122, 685.6092027544252, 746.6416120262196, 802.59545794859, 815.804206990318, 818.145038434837, 3840.8299310889256, 3965.6490265725415, 4286.89282767927, 4295.198749107063, 4348.847790327323, 4202.004048077139, 4327.7186982846015, 4556.0432309214775, 4714.38712967312, 4801.663883528119, 3984.7524267722292, 4087.2029954159248, 4121.876343709693, 4154.335306350969, 4325.610695037399, 2122.1617461103087, 2262.4230821140504, 2383.7377888432256, 2423.5395274897714, 2479.9584174646693, 4199.874003779831, 4464.709793863389, 4809.254565542346, 4872.6832755870755, 4901.803927842846, 4857.781399837016, 4861.927071093936, 4944.722814765274, 4971.0, 5577.163425486209, 5070.540787717954, 5861.824145275648, 5879.559033072451, 5894.705264736832, 5901.734421951022, 5455.345358556999, 5639.605227634191, 5657.236273103243, 5673.404292549223, 5741.2536370273265, 5039.6220283479615, 5190.532852043334, 5216.861086954331, 5588.916166257591, 5697.031504644137, 5021.698698078127, 5484.314460692413, 5897.321807991964, 5906.873525530719, 5991.285403050137, 2968.4359971605327, 3629.7459177857295, 3952.66402355803, 3992.7604343739467, 3998.560518213499, 5380.950714610613, 5745.6552606843725, 5752.367239603724, 5756.560859785054, 5757.424257574209, 4616.722996620214, 5126.743662816919, 5131.897362052813, 5584.162375643524, 5657.0, 4869.6591238612955, 5369.248305237348, 5418.701971391612, 5577.609567330248, 5629.521490673339, 5224.738763061908, 5449.863753406196, 5476.58925580591, 5485.6434331768305, 5524.088525393265, 4643.953560464352, 5566.4990150886615, 5624.325080990144, 5625.484072454716, 5649.887002259849, 5526.834194974157, 5534.7621641340265, 5535.806246781343, 5563.443655634404, 5614.24207731951], "value": 3868.567486074883, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.9787006389809, 721.9422446204304, 772.74499415193, 805.198827166969, 812.4312980913362, 15666.921665391672, 16355.672886542277, 16364.590885227875, 16376.181190940464, 16381.460873564587, 14281.642958926031, 14324.570262892104, 14325.713485730279, 14326.641833954156, 14652.706945861077, 592.8784599157184, 695.151914664109, 826.1160558202725, 836.640244694781, 861.8491763941316, 836.8451836410253, 850.8255807559466, 851.93610479214, 852.0499642898186, 852.7271273192581, 899.7210864631952, 902.6975963052353, 903.3405613901823, 904.3940559824933, 906.0305473143738, 704.7674267491716, 864.2740098317444, 899.3704406915153, 899.9730008099767, 901.0718959471754, 674.5143496682381, 675.7364627795164, 676.0096458688046, 676.3844901139988, 676.5061505101254, 14769.33537990793, 14777.187254700988, 14784.0, 14790.630234244123, 14803.780922437054, 13766.31168441574, 13987.370568324348, 14127.223002734845, 14253.358598863077, 14313.28433578317, 12568.30874301913, 12600.616415811368, 12699.746005079938, 12835.743285134246, 13290.0, 4440.289553671397, 4486.842960496367, 4517.186906356855, 4634.0036892068365, 4707.058588282357, 118946.40526797397, 121047.39476302562, 121059.0, 126987.0, 127085.36457317747, 10992.780144397067, 11230.213885028134, 11323.207375483636, 11375.772484550263, 11520.193586448868, 22539.88730056339, 22573.4356641082, 22576.984035718433, 22642.320730378116, 22643.320700379016, 7816.257455541714, 7841.411894107965, 7864.370850331959, 7892.960535197402, 7938.285554300141, 3562.4834399012516, 3584.7311451640708, 3623.601403845575, 3652.2878038782183, 3674.375356189454, 3348.715359194447, 3406.710429613461, 3419.4186988212064, 3457.498662693946, 3471.739619528495, 651.680676468527, 657.5134400543659, 658.3350815676201, 661.3419647450721, 673.0812441018073, 490.8576512811316, 594.5006194796392, 601.8134378342728, 604.6553464525178, 607.4259824465837, 8870.86693699595, 9121.31590130743, 9123.908760912305, 9131.81736365269, 9165.266778657691, 596.4075917300818, 626.8025571944822, 627.8336240896166, 632.9019002054689, 634.429013887505, 4177.089537423033, 4232.767197804151, 4274.230638485103, 4584.633229341644, 4606.332081848181, 149161.0, 151989.0, 153221.91169927, 157596.0, 158960.20519897557, 130554.04168937473, 133488.0, 135019.32490337486, 135697.0, 135750.0, 79377.20622793814, 97786.0, 98087.01912980921, 105848.47075764571, 111559.0, 9385.953070234606, 9677.129058384779, 9802.215822734306, 9898.802023959624, 9963.651272205438, 45773.0, 49682.50317496779, 51460.0, 61980.38019619746, 62818.0, 63092.0, 73967.63016184885, 93607.25570977085, 94022.05977940133, 96235.0, 11007.339559626447, 12769.361531923552, 13058.0, 13127.60617181448, 13668.31658417095, 12338.951189149024, 12404.317762523147, 12571.245725256515, 12650.367481625697, 14008.929955350159, 13581.0, 14086.436542538184, 14096.929515352358, 14123.364448599632, 14685.853141468877, 11375.658730238107, 13877.375518101911, 14024.158550487004, 14213.786793198316, 14691.632709182357, 13059.412326445523, 13091.476340946636, 13770.242636654679, 14195.290235487977, 14274.000819942505, 11224.933631305013, 11398.373089480157, 11516.712082198012, 11540.538378464767, 11558.768824623292, 8338.639076800782, 9309.627614895327, 11315.207935444769, 11339.376334301693, 11358.60244891425], "value": 7739.963604448674, "unit": "score"}], "name": "dromaeo_css", "value": 7230.936203287243, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 115.0, 113.0, 112.0, 112.0, 112.0, 113.0, 112.0, 123.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 130.0, 159.0, 109.0, 129.0, 128.0, 107.0, 160.0, 108.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 180.0, 183.0, 181.0, 182.0, 182.0, 183.0, 182.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 76.0, 155.0, 71.0, 80.0, 81.0, 84.0, 141.0, 53.0], "value": 89.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 136.0, 71.0, 70.0, 75.0, 121.0, 69.0, 69.0, 121.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 89.0, 85.0, 88.0, 85.0, 93.0, 86.0, 89.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 97.0, 97.0, 97.0, 94.0, 96.0, 96.0, 96.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 91.0, 86.0, 84.0, 86.0, 86.0, 86.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 54.0, 52.0, 54.0, 53.0, 54.0, 53.0, 54.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 76.0, 76.0, 73.0, 68.0, 68.0, 71.0, 71.0, 73.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 88.0, 85.0, 85.0, 87.0, 83.0, 86.0, 88.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 147.0, 146.0, 141.0, 138.0, 139.0, 151.0, 194.0, 150.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 168.0, 167.0, 168.0, 168.0, 166.0, 167.0, 170.0, 166.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 81.0, 82.0, 87.0, 68.0, 66.0, 67.0, 66.0, 66.0, 67.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1471.6, "alertThreshold": 2.0}]} 18:35:00 INFO - Return code: 0 18:35:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:35:00 INFO - # TBPL SUCCESS # 18:35:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:35:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:35:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:35:00 INFO - 2016-09-01 18:35:00.283 screenresolution[1664:9781] starting screenresolution argv=screenresolution get 18:35:00 INFO - 2016-09-01 18:35:00.286 screenresolution[1664:9781] Display 0: 1600x1200x32@60 18:35:00 INFO - 2016-09-01 18:35:00.292 screenresolution[1665:9784] starting screenresolution argv=screenresolution list 18:35:00 INFO - Available Modes on Display 0 18:35:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:35:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:35:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:35:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:35:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:35:00 INFO - Intel Iris: 18:35:00 INFO - Chipset Model: Intel Iris 18:35:00 INFO - Type: GPU 18:35:00 INFO - Bus: Built-In 18:35:00 INFO - VRAM (Dynamic, Max): 1536 MB 18:35:00 INFO - Vendor: Intel (0x8086) 18:35:00 INFO - Device ID: 0x0a2e 18:35:00 INFO - Revision ID: 0x0009 18:35:00 INFO - Displays: 18:35:00 INFO - Display: 18:35:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:35:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:35:00 INFO - Display Serial Number: 1600x1200 60 18:35:00 INFO - Main Display: Yes 18:35:00 INFO - Mirror: Off 18:35:00 INFO - Online: Yes 18:35:00 INFO - Rotation: Supported 18:35:00 INFO - Return code: 0 18:35:00 INFO - Running post-action listener: _resource_record_post_action 18:35:00 INFO - [mozharness: 2016-09-02 01:35:00.527140Z] Finished run-tests step (success) 18:35:00 INFO - Running post-run listener: _resource_record_post_run 18:35:00 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37233664; Write bytes: 705264128; Read time: 236; Write time: 1919 18:35:00 INFO - TinderboxPrint: CPU usage
53.5% 18:35:00 INFO - TinderboxPrint: I/O read bytes / time
37,233,664 / 236 18:35:00 INFO - TinderboxPrint: I/O write bytes / time
705,264,128 / 1,919 18:35:00 INFO - TinderboxPrint: CPU idle
2,253.6 (46.5%) 18:35:00 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 18:35:00 INFO - TinderboxPrint: CPU user
1,626.1 (33.5%) 18:35:00 INFO - TinderboxPrint: Swap in / out
443,088,896 / 0 18:35:00 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394621440; Write bytes: 389326848; Read time: 29428; Write time: 757 18:35:00 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35443712; Write bytes: 295162368; Read time: 221; Write time: 1111 18:35:01 INFO - Running post-run listener: _upload_blobber_files 18:35:01 INFO - Blob upload gear active. 18:35:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:35:01 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 18:35:01 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:35:01 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:35:01 INFO - (blobuploader) - INFO - Open directory for files ... 18:35:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:35:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:35:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:02 INFO - (blobuploader) - INFO - Done attempting. 18:35:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:35:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:35:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:03 INFO - (blobuploader) - INFO - Done attempting. 18:35:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:35:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:35:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:07 INFO - (blobuploader) - INFO - Done attempting. 18:35:07 INFO - (blobuploader) - INFO - Iteration through files over. 18:35:07 INFO - Return code: 0 18:35:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:35:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:35:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bfade782f107447c6156f043af0446d6af51ed45f701160ea21d9eb2aafcc5fa71ddf32c767a71551e95fb941dd735e991282bddd70878a8d3ea73a9f7c2adae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fcbb60c2b8ae08fededdef81e18dcb619943a00034283cffe6746e02a1e82b654198c262793da50e4d06abb7521e95cf16e3364c380320e1ae665f90cf2a4c72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da443ac162e0b40346797206cf4ad2f2861c9290ccb08b10b4e62f3915f8e56db88d43fdb21145f12cb56b0539e9a2d06f5f00cad167cd3f522dc2011f30263c"} 18:35:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:35:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:35:07 INFO - Contents: 18:35:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bfade782f107447c6156f043af0446d6af51ed45f701160ea21d9eb2aafcc5fa71ddf32c767a71551e95fb941dd735e991282bddd70878a8d3ea73a9f7c2adae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fcbb60c2b8ae08fededdef81e18dcb619943a00034283cffe6746e02a1e82b654198c262793da50e4d06abb7521e95cf16e3364c380320e1ae665f90cf2a4c72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da443ac162e0b40346797206cf4ad2f2861c9290ccb08b10b4e62f3915f8e56db88d43fdb21145f12cb56b0539e9a2d06f5f00cad167cd3f522dc2011f30263c"} 18:35:07 INFO - Running post-run listener: copy_logs_to_upload_dir 18:35:07 INFO - Copying logs to upload dir... 18:35:07 INFO - mkdir: /builds/slave/test/build/upload/logs 18:35:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.930361 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-01 18:35:07.571454) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 18:35:07.574808) ========= 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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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/Autoland/sha512/bfade782f107447c6156f043af0446d6af51ed45f701160ea21d9eb2aafcc5fa71ddf32c767a71551e95fb941dd735e991282bddd70878a8d3ea73a9f7c2adae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fcbb60c2b8ae08fededdef81e18dcb619943a00034283cffe6746e02a1e82b654198c262793da50e4d06abb7521e95cf16e3364c380320e1ae665f90cf2a4c72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da443ac162e0b40346797206cf4ad2f2861c9290ccb08b10b4e62f3915f8e56db88d43fdb21145f12cb56b0539e9a2d06f5f00cad167cd3f522dc2011f30263c"} build_url:https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011014 build_url: 'https://queue.taskcluster.net/v1/task/afIintCjRaehyBzv6UkSSw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bfade782f107447c6156f043af0446d6af51ed45f701160ea21d9eb2aafcc5fa71ddf32c767a71551e95fb941dd735e991282bddd70878a8d3ea73a9f7c2adae", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fcbb60c2b8ae08fededdef81e18dcb619943a00034283cffe6746e02a1e82b654198c262793da50e4d06abb7521e95cf16e3364c380320e1ae665f90cf2a4c72", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/da443ac162e0b40346797206cf4ad2f2861c9290ccb08b10b4e62f3915f8e56db88d43fdb21145f12cb56b0539e9a2d06f5f00cad167cd3f522dc2011f30263c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 18:35:07.610425) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:35:07.611006) ========= 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.ztjXiqY5NS/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.xV4ffsGgvj/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.005317 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:35:07.660311) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 18:35:07.660890) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 18:35:07.661246) ========= ========= Total master_lag: 0.54 =========