builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0344 starttime: 1471057679.08 results: success (0) buildid: 20160812163917 builduid: 78bc0bc6e5d64a27b47c64984f6ecf30 revision: 928b859ecc50399d182447db0e4826bc478a4c8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 20:07:59.080774) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-12 20:07:59.081188) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 20:07:59.081458) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-12 20:07:59.105842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 20:07:59.106133) ========= 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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-12 20:07:59-- 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% 170M=0s 2016-08-12 20:07:59 (170 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117368 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 20:07:59.245586) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 20:07:59.245921) ========= 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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.025345 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 20:07:59.294519) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 20:07:59.294923) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 928b859ecc50399d182447db0e4826bc478a4c8d --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 928b859ecc50399d182447db0e4826bc478a4c8d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.G5l5HUfQK7/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.b1RwRVUcbs/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-12 20:07:59,372 truncating revision to first 12 chars 2016-08-12 20:07:59,372 Setting DEBUG logging. 2016-08-12 20:07:59,372 attempt 1/10 2016-08-12 20:07:59,372 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/928b859ecc50?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-12 20:08:00,076 unpacking tar archive at: autoland-928b859ecc50/testing/mozharness/ program finished with exit code 0 elapsedTime=0.982920 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-12 20:08:00.299681) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 20:08:00.299999) ========= script_repo_revision: 928b859ecc50399d182447db0e4826bc478a4c8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-12 20:08:00.300378) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 20:08:00.300654) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-12 20:08:00.318915) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-12 20:08:00.319205) ========= /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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08:00 INFO - MultiFileLogger online at 20160812 20:08:00 in /builds/slave/test 20:08:00 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 20:08:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:08:00 INFO - {'append_to_log': False, 20:08:00 INFO - 'base_work_dir': '/builds/slave/test', 20:08:00 INFO - 'blob_upload_branch': 'Autoland', 20:08:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:08:00 INFO - 'branch': 'Autoland', 20:08:00 INFO - 'buildbot_json_path': 'buildprops.json', 20:08:00 INFO - 'config_files': ('talos/mac_config.py',), 20:08:00 INFO - 'default_actions': ('clobber', 20:08:00 INFO - 'read-buildbot-config', 20:08:00 INFO - 'download-and-extract', 20:08:00 INFO - 'populate-webroot', 20:08:00 INFO - 'create-virtualenv', 20:08:00 INFO - 'install', 20:08:00 INFO - 'run-tests'), 20:08:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:08:00 INFO - 'download_minidump_stackwalk': True, 20:08:00 INFO - 'download_symbols': 'ondemand', 20:08:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:08:00 INFO - 'tooltool.py': '/tools/tooltool.py', 20:08:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:08:00 INFO - '/tools/misc-python/virtualenv.py')}, 20:08:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:08:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:08:00 INFO - 'installer_path': 'installer.exe', 20:08:00 INFO - 'log_level': 'info', 20:08:00 INFO - 'log_name': 'talos', 20:08:00 INFO - 'log_to_console': True, 20:08:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:08:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:08:00 INFO - 'opt_config_files': (), 20:08:00 INFO - 'pip_index': False, 20:08:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:08:00 INFO - 'cmd': ('bash', 20:08:00 INFO - '-c', 20:08:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:08:00 INFO - 'enabled': True, 20:08:00 INFO - 'halt_on_failure': False, 20:08:00 INFO - 'name': 'check_screen_resolution'},), 20:08:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:08:00 INFO - 'cmd': ('bash', 20:08:00 INFO - '-c', 20:08:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:08:00 INFO - 'enabled': True, 20:08:00 INFO - 'halt_on_failure': False, 20:08:00 INFO - 'name': 'check_screen_resolution'},), 20:08:00 INFO - 'run_cmd_checks_enabled': True, 20:08:00 INFO - 'sps_profile': False, 20:08:00 INFO - 'sps_profile_interval': 0, 20:08:00 INFO - 'suite': 'dromaeojs', 20:08:00 INFO - 'system_bits': '32', 20:08:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:08:00 INFO - 'title': 't-yosemite-r7-0344', 20:08:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:08:00 INFO - 'use_talos_json': True, 20:08:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:08:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:08:00 INFO - 'work_dir': 'build'} 20:08:00 INFO - [mozharness: 2016-08-13 03:08:00.516999Z] Running clobber step. 20:08:00 INFO - Running pre-action listener: _resource_record_pre_action 20:08:00 INFO - Running main action method: clobber 20:08:00 INFO - rmtree: /builds/slave/test/build 20:08:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:08:02 INFO - Running post-action listener: _resource_record_post_action 20:08:02 INFO - [mozharness: 2016-08-13 03:08:02.405548Z] Finished clobber step (success) 20:08:02 INFO - [mozharness: 2016-08-13 03:08:02.405707Z] Running read-buildbot-config step. 20:08:02 INFO - Running pre-action listener: _resource_record_pre_action 20:08:02 INFO - Running main action method: read_buildbot_config 20:08:02 INFO - Using buildbot properties: 20:08:02 INFO - { 20:08:02 INFO - "project": "", 20:08:02 INFO - "product": "firefox", 20:08:02 INFO - "script_repo_revision": "production", 20:08:02 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 20:08:02 INFO - "repository": "", 20:08:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 20:08:02 INFO - "buildid": "20160812163917", 20:08:02 INFO - "pgo_build": "False", 20:08:02 INFO - "basedir": "/builds/slave/test", 20:08:02 INFO - "buildnumber": 113, 20:08:02 INFO - "slavename": "t-yosemite-r7-0344", 20:08:02 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 20:08:02 INFO - "platform": "yosemite_r7", 20:08:02 INFO - "scheduler": "tests-autoland-macosx64-talos", 20:08:02 INFO - "branch": "autoland", 20:08:02 INFO - "repo_path": "integration/autoland", 20:08:02 INFO - "revision": "928b859ecc50399d182447db0e4826bc478a4c8d", 20:08:02 INFO - "stage_platform": "macosx64", 20:08:02 INFO - "builduid": "78bc0bc6e5d64a27b47c64984f6ecf30", 20:08:02 INFO - "slavebuilddir": "test" 20:08:02 INFO - } 20:08:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 20:08:02 INFO - Running post-action listener: _resource_record_post_action 20:08:02 INFO - [mozharness: 2016-08-13 03:08:02.409156Z] Finished read-buildbot-config step (success) 20:08:02 INFO - [mozharness: 2016-08-13 03:08:02.409289Z] Running download-and-extract step. 20:08:02 INFO - Running pre-action listener: _resource_record_pre_action 20:08:02 INFO - Running main action method: download_and_extract 20:08:02 INFO - mkdir: /builds/slave/test/build/tests 20:08:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:02 INFO - trying https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 20:08:02 INFO - Downloading https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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 20:08:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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 20:08:03 INFO - Downloaded 1199 bytes. 20:08:03 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 20:08:03 INFO - Using the following test package requirements: 20:08:03 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:08:03 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:03 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 20:08:03 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:03 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 20:08:03 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:03 INFO - u'jsshell-mac.zip'], 20:08:03 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:03 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 20:08:03 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:08:03 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:03 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 20:08:03 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:03 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 20:08:03 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:03 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 20:08:03 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:08:03 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 20:08:03 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:03 INFO - trying https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:08:03 INFO - Downloading https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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 20:08:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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 20:08:04 INFO - Downloaded 16331583 bytes. 20:08:04 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:08:11 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 20:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:11 INFO - trying https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:08:11 INFO - Downloading https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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 20:08:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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 20:08:12 INFO - Downloaded 16331583 bytes. 20:08:12 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:18 INFO - trying https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 20:08:18 INFO - Downloading https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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 20:08:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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 20:08:20 INFO - Downloaded 14413719 bytes. 20:08:20 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 20:08: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')]} 20:08:20 INFO - trying https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:08:20 INFO - Downloading https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 20:08:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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 20:08:23 INFO - Downloaded 111027032 bytes. 20:08:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:08:23 INFO - mkdir: /builds/slave/test/properties 20:08:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:08:23 INFO - Writing to file /builds/slave/test/properties/build_url 20:08:23 INFO - Contents: 20:08:23 INFO - build_url:https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:08:24 INFO - Running post-action listener: _resource_record_post_action 20:08:24 INFO - Running post-action listener: set_extra_try_arguments 20:08:24 INFO - [mozharness: 2016-08-13 03:08:24.322140Z] Finished download-and-extract step (success) 20:08:24 INFO - [mozharness: 2016-08-13 03:08:24.322269Z] Running populate-webroot step. 20:08:24 INFO - Running pre-action listener: _resource_record_pre_action 20:08:24 INFO - Running main action method: populate_webroot 20:08:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:08:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:08:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:08:24 INFO - '--tppagecycles', 20:08:24 INFO - '7'], 20:08:24 INFO - 'tests': ['tsvgm']}}, 20:08:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:08:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:08:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:08:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:08:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:24 INFO - 'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:08:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:08:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:24 INFO - 'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['damp', 'tps']}, 20:08:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:24 INFO - 'tests': ['damp', 'tps']}, 20:08:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['dromaeo_dom']}, 20:08:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:08:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['basic_compositor_video']}, 20:08:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:08:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['a11yr', 20:08:24 INFO - 'ts_paint', 20:08:24 INFO - 'tpaint', 20:08:24 INFO - 'sessionrestore', 20:08:24 INFO - 'sessionrestore_no_auto_restore', 20:08:24 INFO - 'tabpaint']}, 20:08:24 INFO - 'other-e10s': {'tests': ['a11yr', 20:08:24 INFO - 'ts_paint', 20:08:24 INFO - 'tpaint', 20:08:24 INFO - 'sessionrestore', 20:08:24 INFO - 'sessionrestore_no_auto_restore', 20:08:24 INFO - 'tabpaint']}, 20:08:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:08:24 INFO - 'ts_paint', 20:08:24 INFO - 'tpaint', 20:08:24 INFO - 'sessionrestore', 20:08:24 INFO - 'sessionrestore_no_auto_restore', 20:08:24 INFO - 'tabpaint']}, 20:08:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:08:24 INFO - 'ts_paint', 20:08:24 INFO - 'tpaint', 20:08:24 INFO - 'sessionrestore', 20:08:24 INFO - 'sessionrestore_no_auto_restore', 20:08:24 INFO - 'tabpaint']}, 20:08:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['a11yr', 20:08:24 INFO - 'ts_paint', 20:08:24 INFO - 'tpaint', 20:08:24 INFO - 'sessionrestore', 20:08:24 INFO - 'sessionrestore_no_auto_restore', 20:08:24 INFO - 'tabpaint']}, 20:08:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['a11yr', 20:08:24 INFO - 'ts_paint', 20:08:24 INFO - 'tpaint', 20:08:24 INFO - 'sessionrestore', 20:08:24 INFO - 'sessionrestore_no_auto_restore', 20:08:24 INFO - 'tabpaint']}, 20:08:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['tsvgx', 20:08:24 INFO - 'tsvgr_opacity', 20:08:24 INFO - 'tart', 20:08:24 INFO - 'tscrollx', 20:08:24 INFO - 'cart']}, 20:08:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:08:24 INFO - 'tsvgr_opacity', 20:08:24 INFO - 'tart', 20:08:24 INFO - 'tscrollx', 20:08:24 INFO - 'cart']}, 20:08:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:24 INFO - 'talos_options': ['--disable-e10s'], 20:08:24 INFO - 'tests': ['tp5o']}, 20:08:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:24 INFO - 'tests': ['tp5o']}, 20:08:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:24 INFO - 'talos_options': ['--disable-e10s', 20:08:24 INFO - '--xperf_path', 20:08:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:08:24 INFO - 'tests': ['tp5n']}, 20:08:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:08:24 INFO - 'talos_options': ['--xperf_path', 20:08:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:08:24 INFO - 'tests': ['tp5n']}}, 20:08:24 INFO - 'talos.zip': {'path': '', 20:08:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:08:24 INFO - Running post-action listener: _resource_record_post_action 20:08:24 INFO - [mozharness: 2016-08-13 03:08:24.340813Z] Finished populate-webroot step (success) 20:08:24 INFO - [mozharness: 2016-08-13 03:08:24.340947Z] Running create-virtualenv step. 20:08:24 INFO - Running pre-action listener: _resource_record_pre_action 20:08:24 INFO - Running main action method: create_virtualenv 20:08:24 INFO - Creating virtualenv /builds/slave/test/build/venv 20:08:24 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 20:08:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:08:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:08:24 INFO - Using real prefix '/tools/python27' 20:08:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:08:25 INFO - Installing distribute.............................................................................................................................................................................................done. 20:08:28 INFO - Installing pip.................done. 20:08:28 INFO - Return code: 0 20:08:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:08: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')]} 20:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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')]} 20:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08: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', 'pip>=1.5'] in /builds/slave/test/build 20:08: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 pip>=1.5 20:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:28 INFO - 'HOME': '/Users/cltbld', 20:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:28 INFO - 'LOGNAME': 'cltbld', 20:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:28 INFO - 'MOZ_NO_REMOTE': '1', 20:08:28 INFO - 'NO_EM_RESTART': '1', 20:08:28 INFO - 'PAGER': '/bin/cat', 20:08:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:28 INFO - 'PWD': '/builds/slave/test', 20:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:28 INFO - 'SHELL': '/bin/bash', 20:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:28 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:28 INFO - 'USER': 'cltbld', 20:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:28 INFO - 'XPC_FLAGS': '0x0', 20:08:28 INFO - 'XPC_SERVICE_NAME': '0', 20:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:28 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 20:08:28 INFO - Cleaning up... 20:08:28 INFO - Return code: 0 20:08:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:08: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')]} 20:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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')]} 20:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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', '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 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08: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', 'psutil>=3.1.1'] in /builds/slave/test/build 20:08: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 psutil>=3.1.1 20:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:28 INFO - 'HOME': '/Users/cltbld', 20:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:28 INFO - 'LOGNAME': 'cltbld', 20:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:28 INFO - 'MOZ_NO_REMOTE': '1', 20:08:28 INFO - 'NO_EM_RESTART': '1', 20:08:28 INFO - 'PAGER': '/bin/cat', 20:08:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:28 INFO - 'PWD': '/builds/slave/test', 20:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:28 INFO - 'SHELL': '/bin/bash', 20:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:28 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:28 INFO - 'USER': 'cltbld', 20:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:28 INFO - 'XPC_FLAGS': '0x0', 20:08:28 INFO - 'XPC_SERVICE_NAME': '0', 20:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:28 INFO - Downloading/unpacking psutil>=3.1.1 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:08:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:31 INFO - Installing collected packages: psutil 20:08:31 INFO - Running setup.py install for psutil 20:08:31 INFO - building 'psutil._psutil_osx' extension 20:08:31 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 20:08:32 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 20:08:32 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 20:08:32 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 20:08:32 INFO - building 'psutil._psutil_posix' extension 20:08:32 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 20:08:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:08:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:08:32 INFO - ^ 20:08:32 INFO - 1 warning generated. 20:08:32 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 20:08:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:32 INFO - Successfully installed psutil 20:08:32 INFO - Cleaning up... 20:08:32 INFO - Return code: 0 20:08:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:08: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')]} 20:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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')]} 20:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:08: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 mozsystemmonitor==0.3 20:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:32 INFO - 'HOME': '/Users/cltbld', 20:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:32 INFO - 'LOGNAME': 'cltbld', 20:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:32 INFO - 'MOZ_NO_REMOTE': '1', 20:08:32 INFO - 'NO_EM_RESTART': '1', 20:08:32 INFO - 'PAGER': '/bin/cat', 20:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:32 INFO - 'PWD': '/builds/slave/test', 20:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:32 INFO - 'SHELL': '/bin/bash', 20:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:32 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:32 INFO - 'USER': 'cltbld', 20:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:32 INFO - 'XPC_FLAGS': '0x0', 20:08:32 INFO - 'XPC_SERVICE_NAME': '0', 20:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:08:35 INFO - Installing collected packages: mozsystemmonitor 20:08:35 INFO - Running setup.py install for mozsystemmonitor 20:08:35 INFO - Successfully installed mozsystemmonitor 20:08:35 INFO - Cleaning up... 20:08:35 INFO - Return code: 0 20:08:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:08:35 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')]} 20:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:35 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')]} 20:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:35 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 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08:35 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 20:08:35 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 20:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:35 INFO - 'HOME': '/Users/cltbld', 20:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:35 INFO - 'LOGNAME': 'cltbld', 20:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:35 INFO - 'MOZ_NO_REMOTE': '1', 20:08:35 INFO - 'NO_EM_RESTART': '1', 20:08:35 INFO - 'PAGER': '/bin/cat', 20:08:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:35 INFO - 'PWD': '/builds/slave/test', 20:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:35 INFO - 'SHELL': '/bin/bash', 20:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:35 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:35 INFO - 'USER': 'cltbld', 20:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:35 INFO - 'XPC_FLAGS': '0x0', 20:08:35 INFO - 'XPC_SERVICE_NAME': '0', 20:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:35 INFO - Downloading/unpacking jsonschema==2.5.1 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:08:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:08:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:38 INFO - Downloading functools32-3.2.3-2.tar.gz 20:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:08:38 INFO - warning: no files found matching '*.txt' 20:08:38 INFO - no previously-included directories found matching 'build' 20:08:38 INFO - no previously-included directories found matching 'dist' 20:08:38 INFO - no previously-included directories found matching '.git*' 20:08:38 INFO - Installing collected packages: jsonschema, functools32 20:08:38 INFO - Running setup.py install for functools32 20:08:39 INFO - warning: no files found matching '*.txt' 20:08:39 INFO - no previously-included directories found matching 'build' 20:08:39 INFO - no previously-included directories found matching 'dist' 20:08:39 INFO - no previously-included directories found matching '.git*' 20:08:39 INFO - Successfully installed jsonschema functools32 20:08:39 INFO - Cleaning up... 20:08:39 INFO - Return code: 0 20:08:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:08:39 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')]} 20:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:39 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')]} 20:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:39 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 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08:39 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 20:08:39 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 20:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:39 INFO - 'HOME': '/Users/cltbld', 20:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:39 INFO - 'LOGNAME': 'cltbld', 20:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:39 INFO - 'MOZ_NO_REMOTE': '1', 20:08:39 INFO - 'NO_EM_RESTART': '1', 20:08:39 INFO - 'PAGER': '/bin/cat', 20:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:39 INFO - 'PWD': '/builds/slave/test', 20:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:39 INFO - 'SHELL': '/bin/bash', 20:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:39 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:39 INFO - 'USER': 'cltbld', 20:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:39 INFO - 'XPC_FLAGS': '0x0', 20:08:39 INFO - 'XPC_SERVICE_NAME': '0', 20:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:08:39 INFO - Cleaning up... 20:08:39 INFO - Return code: 0 20:08:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:08:39 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')]} 20:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:39 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')]} 20:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:39 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 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08:39 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 20:08:39 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 20:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:39 INFO - 'HOME': '/Users/cltbld', 20:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:39 INFO - 'LOGNAME': 'cltbld', 20:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:39 INFO - 'MOZ_NO_REMOTE': '1', 20:08:39 INFO - 'NO_EM_RESTART': '1', 20:08:39 INFO - 'PAGER': '/bin/cat', 20:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:39 INFO - 'PWD': '/builds/slave/test', 20:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:39 INFO - 'SHELL': '/bin/bash', 20:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:39 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:39 INFO - 'USER': 'cltbld', 20:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:39 INFO - 'XPC_FLAGS': '0x0', 20:08:39 INFO - 'XPC_SERVICE_NAME': '0', 20:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:39 INFO - Downloading/unpacking blobuploader==1.2.4 20:08:39 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 20:08:39 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 20:08:39 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 20:08:39 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 20:08:39 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 20:08:39 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 20:08:42 INFO - Downloading blobuploader-1.2.4.tar.gz 20:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:08:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:08:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:43 INFO - Downloading docopt-0.6.1.tar.gz 20:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:08:43 INFO - Installing collected packages: blobuploader, requests, docopt 20:08:43 INFO - Running setup.py install for blobuploader 20:08:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:08:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:08:43 INFO - Running setup.py install for requests 20:08:44 INFO - Running setup.py install for docopt 20:08:44 INFO - Successfully installed blobuploader requests docopt 20:08:44 INFO - Cleaning up... 20:08:44 INFO - Return code: 0 20:08:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:44 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 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08:44 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 20:08:44 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 20:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:44 INFO - 'HOME': '/Users/cltbld', 20:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:44 INFO - 'LOGNAME': 'cltbld', 20:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:44 INFO - 'MOZ_NO_REMOTE': '1', 20:08:44 INFO - 'NO_EM_RESTART': '1', 20:08:44 INFO - 'PAGER': '/bin/cat', 20:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:44 INFO - 'PWD': '/builds/slave/test', 20:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:44 INFO - 'SHELL': '/bin/bash', 20:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:44 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:44 INFO - 'USER': 'cltbld', 20:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:44 INFO - 'XPC_FLAGS': '0x0', 20:08:44 INFO - 'XPC_SERVICE_NAME': '0', 20:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:44 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-nkox51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:44 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-RXtvB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:44 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-JEinoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:44 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-tCOnII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:44 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-hk7Zqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-1dIVMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-EU0Ihk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-9vKkKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ru63iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-AfoLUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-3fwsG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-G3eg7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-d8fyQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-JMAfyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:45 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-yqL0cM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:46 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-XA65zK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:46 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-GpagXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:08:46 INFO - Running setup.py install for manifestparser 20:08:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:08:46 INFO - Running setup.py install for mozcrash 20:08:46 INFO - Running setup.py install for mozdebug 20:08:46 INFO - Running setup.py install for mozdevice 20:08:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:08:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:08:46 INFO - Running setup.py install for mozfile 20:08:47 INFO - Running setup.py install for mozhttpd 20:08:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:08:47 INFO - Running setup.py install for mozinfo 20:08:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:08:47 INFO - Running setup.py install for mozInstall 20:08:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:08:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:08:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:08:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:08:47 INFO - Running setup.py install for mozleak 20:08:47 INFO - Running setup.py install for mozlog 20:08:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:08:47 INFO - Running setup.py install for moznetwork 20:08:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:08:47 INFO - Running setup.py install for mozprocess 20:08:48 INFO - Running setup.py install for mozprofile 20:08:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:08:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:08:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:08:48 INFO - Running setup.py install for mozrunner 20:08:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:08:48 INFO - Running setup.py install for mozscreenshot 20:08:48 INFO - Running setup.py install for moztest 20:08:48 INFO - Running setup.py install for mozversion 20:08:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:08:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:08:48 INFO - Cleaning up... 20:08:48 INFO - Return code: 0 20:08:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:48 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 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08:48 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 20:08:48 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 20:08:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:48 INFO - 'HOME': '/Users/cltbld', 20:08:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:48 INFO - 'LOGNAME': 'cltbld', 20:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:48 INFO - 'MOZ_NO_REMOTE': '1', 20:08:48 INFO - 'NO_EM_RESTART': '1', 20:08:48 INFO - 'PAGER': '/bin/cat', 20:08:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:48 INFO - 'PWD': '/builds/slave/test', 20:08:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:48 INFO - 'SHELL': '/bin/bash', 20:08:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:48 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:48 INFO - 'USER': 'cltbld', 20:08:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:48 INFO - 'XPC_FLAGS': '0x0', 20:08:48 INFO - 'XPC_SERVICE_NAME': '0', 20:08:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:49 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-F52YSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:49 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)) 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:49 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ucbj31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:49 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)) 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:49 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-fHRdkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:49 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)) 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:49 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-yu7RG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:49 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)) 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:49 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-5edp2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:49 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)) 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:49 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-1JtlZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:49 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)) 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:49 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-0F5_5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:49 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)) 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:49 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-RrrNCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:50 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)) 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:50 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-2ZnXVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:50 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)) 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:50 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-ujHGxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:50 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)) 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:50 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-gQ12N0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:50 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)) 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:50 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-13TOpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:50 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)) 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:50 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-2ZBwjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:50 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)) 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:50 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-PtPfX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:50 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)) 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:50 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-na90WP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:50 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)) 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:50 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-BFV8Zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:50 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)) 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:50 INFO - Running setup.py (path:/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/pip-RvuaxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:50 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)) 20:08:50 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)) 20:08:50 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)) 20:08:50 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)) 20:08:50 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)) 20:08:50 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)) 20:08:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:08:50 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 20:08:50 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 20:08:50 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 20:08:50 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 20:08:50 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 20:08:50 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 20:08:53 INFO - Downloading blessings-1.6.tar.gz 20:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:08:53 INFO - Installing collected packages: blessings 20:08:53 INFO - Running setup.py install for blessings 20:08:53 INFO - Successfully installed blessings 20:08:53 INFO - Cleaning up... 20:08:53 INFO - Return code: 0 20:08:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:08:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:08:54 INFO - Reading from file tmpfile_stdout 20:08:54 INFO - Current package versions: 20:08:54 INFO - blessings == 1.6 20:08:54 INFO - blobuploader == 1.2.4 20:08:54 INFO - docopt == 0.6.1 20:08:54 INFO - functools32 == 3.2.3-2 20:08:54 INFO - jsonschema == 2.5.1 20:08:54 INFO - manifestparser == 1.1 20:08:54 INFO - mozInstall == 1.12 20:08:54 INFO - mozcrash == 0.17 20:08:54 INFO - mozdebug == 0.1 20:08:54 INFO - mozdevice == 0.48 20:08:54 INFO - mozfile == 1.2 20:08:54 INFO - mozhttpd == 0.7 20:08:54 INFO - mozinfo == 0.9 20:08:54 INFO - mozleak == 0.1 20:08:54 INFO - mozlog == 3.3 20:08:54 INFO - moznetwork == 0.27 20:08:54 INFO - mozprocess == 0.23 20:08:54 INFO - mozprofile == 0.28 20:08:54 INFO - mozrunner == 6.12 20:08:54 INFO - mozscreenshot == 0.1 20:08:54 INFO - mozsystemmonitor == 0.3 20:08:54 INFO - moztest == 0.7 20:08:54 INFO - mozversion == 1.4 20:08:54 INFO - psutil == 3.1.1 20:08:54 INFO - requests == 1.2.3 20:08:54 INFO - wsgiref == 0.1.2 20:08:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:54 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')]} 20:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:54 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')]} 20:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:54 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 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08:54 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 20:08:54 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 20:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:54 INFO - 'HOME': '/Users/cltbld', 20:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:54 INFO - 'LOGNAME': 'cltbld', 20:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:54 INFO - 'MOZ_NO_REMOTE': '1', 20:08:54 INFO - 'NO_EM_RESTART': '1', 20:08:54 INFO - 'PAGER': '/bin/cat', 20:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:54 INFO - 'PWD': '/builds/slave/test', 20:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:54 INFO - 'SHELL': '/bin/bash', 20:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:54 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:54 INFO - 'USER': 'cltbld', 20:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:54 INFO - 'XPC_FLAGS': '0x0', 20:08:54 INFO - 'XPC_SERVICE_NAME': '0', 20:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:54 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)) 20:08:54 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)) 20:08:54 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)) 20:08:54 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)) 20:08:54 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)) 20:08:54 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)) 20:08:54 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)) 20:08:54 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)) 20:08:54 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)) 20:08:54 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)) 20:08:54 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)) 20:08:54 INFO - Cleaning up... 20:08:54 INFO - Return code: 0 20:08:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:08:54 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')]} 20:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:54 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')]} 20:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:54 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 0x109daba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e40618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3404c82a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, '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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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 20:08:54 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 20:08:54 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 20:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:54 INFO - 'HOME': '/Users/cltbld', 20:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:54 INFO - 'LOGNAME': 'cltbld', 20:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:54 INFO - 'MOZ_NO_REMOTE': '1', 20:08:54 INFO - 'NO_EM_RESTART': '1', 20:08:54 INFO - 'PAGER': '/bin/cat', 20:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:54 INFO - 'PWD': '/builds/slave/test', 20:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:54 INFO - 'SHELL': '/bin/bash', 20:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:08:54 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:54 INFO - 'USER': 'cltbld', 20:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:54 INFO - 'XPC_FLAGS': '0x0', 20:08:54 INFO - 'XPC_SERVICE_NAME': '0', 20:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:08:54 INFO - Cleaning up... 20:08:54 INFO - Return code: 0 20:08:54 INFO - Running post-action listener: _resource_record_post_action 20:08:54 INFO - Running post-action listener: _start_resource_monitoring 20:08:54 INFO - Starting resource monitoring. 20:08:54 INFO - [mozharness: 2016-08-13 03:08:54.622596Z] Finished create-virtualenv step (success) 20:08:54 INFO - [mozharness: 2016-08-13 03:08:54.623616Z] Running install step. 20:08:54 INFO - Running pre-action listener: _resource_record_pre_action 20:08:54 INFO - Running main action method: install 20:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:08:54 INFO - Reading from file tmpfile_stdout 20:08:54 INFO - Detecting whether we're running mozinstall >=1.0... 20:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:08:54 INFO - Reading from file tmpfile_stdout 20:08:54 INFO - Output received: 20:08:54 INFO - Usage: mozinstall [options] installer 20:08:54 INFO - Options: 20:08:54 INFO - -h, --help show this help message and exit 20:08:54 INFO - -d DEST, --destination=DEST 20:08:54 INFO - Directory to install application into. [default: 20:08:54 INFO - "/builds/slave/test"] 20:08:54 INFO - --app=APP Application being installed. [default: firefox] 20:08:54 INFO - mkdir: /builds/slave/test/build/application 20:08:54 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'] 20:08:54 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 20:09:29 INFO - Reading from file tmpfile_stdout 20:09:29 INFO - Output received: 20:09:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:09:29 INFO - Running post-action listener: _resource_record_post_action 20:09:29 INFO - [mozharness: 2016-08-13 03:09:29.500214Z] Finished install step (success) 20:09:29 INFO - [mozharness: 2016-08-13 03:09:29.500348Z] Running run-tests step. 20:09:29 INFO - Running pre-action listener: _resource_record_pre_action 20:09:29 INFO - Running main action method: run_tests 20:09:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:09:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:09:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:09:29 INFO - 2016-08-12 20:09:29.514 screenresolution[1637:7568] starting screenresolution argv=screenresolution get 20:09:29 INFO - 2016-08-12 20:09:29.517 screenresolution[1637:7568] Display 0: 1600x1200x32@60 20:09:29 INFO - 2016-08-12 20:09:29.522 screenresolution[1638:7570] starting screenresolution argv=screenresolution list 20:09:29 INFO - Available Modes on Display 0 20:09:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:09:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:09:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:09:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:09:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:09:29 INFO - Intel Iris: 20:09:29 INFO - Chipset Model: Intel Iris 20:09:29 INFO - Type: GPU 20:09:29 INFO - Bus: Built-In 20:09:29 INFO - VRAM (Dynamic, Max): 1536 MB 20:09:29 INFO - Vendor: Intel (0x8086) 20:09:29 INFO - Device ID: 0x0a2e 20:09:29 INFO - Revision ID: 0x0009 20:09:29 INFO - Displays: 20:09:29 INFO - Display: 20:09:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:09:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:09:29 INFO - Display Serial Number: 1600x1200 60 20:09:29 INFO - Main Display: Yes 20:09:29 INFO - Mirror: Off 20:09:29 INFO - Online: Yes 20:09:29 INFO - Rotation: Supported 20:09:29 INFO - Return code: 0 20:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:09:29 INFO - Python 2.7.3 20:09:29 INFO - Return code: 0 20:09:29 INFO - grabbing minidump binary from tooltool 20:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:29 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 0x109d0fd30>, '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 0x109a74db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109d07b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:09:29 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 20:09:29 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 20:09:29 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 20:09:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:09:29 INFO - Return code: 0 20:09:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:09:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:09:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:09:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:09:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:09:29 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/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 20:09:29 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/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0344 --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 20:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.G5l5HUfQK7/Render', 20:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:29 INFO - 'HOME': '/Users/cltbld', 20:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:29 INFO - 'LOGNAME': 'cltbld', 20:09:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:09:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:29 INFO - 'MOZ_NO_REMOTE': '1', 20:09:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:09:29 INFO - 'NO_EM_RESTART': '1', 20:09:29 INFO - 'PAGER': '/bin/cat', 20:09:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:29 INFO - 'PWD': '/builds/slave/test', 20:09:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:29 INFO - 'SHELL': '/bin/bash', 20:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b1RwRVUcbs/Listeners', 20:09:29 INFO - 'TMPDIR': '/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/', 20:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:29 INFO - 'USER': 'cltbld', 20:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:29 INFO - 'XPC_FLAGS': '0x0', 20:09:29 INFO - 'XPC_SERVICE_NAME': '0', 20:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:29 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/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0344', '--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 20:09:30 INFO - mozversion application_buildid: 20160812163917 20:09:30 INFO - mozversion application_changeset: 928b859ecc50399d182447db0e4826bc478a4c8d 20:09:30 INFO - mozversion application_display_name: Nightly 20:09:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:09:30 INFO - mozversion application_name: Firefox 20:09:30 INFO - mozversion application_remotingname: firefox 20:09:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:09:30 INFO - mozversion application_vendor: Mozilla 20:09:30 INFO - mozversion application_version: 51.0a1 20:09:30 INFO - mozversion platform_buildid: 20160812163917 20:09:30 INFO - mozversion platform_changeset: 928b859ecc50399d182447db0e4826bc478a4c8d 20:09:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:09:30 INFO - mozversion platform_version: 51.0a1 20:09:30 INFO - using testdate: 1471057770 20:09:30 INFO - actual date: 1471057770 20:09:30 INFO - starting webserver on 'localhost:49268' 20:09:30 INFO - SUITE-START | Running 2 tests 20:09:30 INFO - TEST-START | dromaeo_css 20:09:30 INFO - operating with platform_type : mac_ 20:09:30 INFO - Initialising browser for dromaeo_css test... 20:09:30 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpiXEjk5/profile http://localhost:49268/getInfo.html) 20:09:32 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 20:09:32 INFO - PROCESS | 1648 | colorDepth:24 20:09:32 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 20:09:32 INFO - PROCESS | 1648 | __metrics 20:09:32 INFO - PROCESS | 1648 | 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" 20:09:32 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:09:33 INFO - TEST-INFO | 1648: exit 0 20:09:33 INFO - Browser initialized. 20:09:33 INFO - Running cycle 1/1 for dromaeo_css test... 20:09:33 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpiXEjk5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:09:45 INFO - PROCESS | 1650 | RSS: Main: 228581376 20:09:45 INFO - PROCESS | 1650 | 20:10:36 INFO - PROCESS | 1650 | 1471057836462 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] 20:12:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 20:12:50 INFO - PROCESS | 1650 | RSS: Main: 379060224 20:12:50 INFO - PROCESS | 1650 | 20:15:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 20:15:54 INFO - PROCESS | 1650 | RSS: Main: 371347456 20:15:54 INFO - PROCESS | 1650 | 20:18:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 20:18:57 INFO - PROCESS | 1650 | RSS: Main: 366202880 20:18:57 INFO - PROCESS | 1650 | 20:21:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 20:21:59 INFO - PROCESS | 1650 | RSS: Main: 356581376 20:21:59 INFO - PROCESS | 1650 | 20:25:03 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 20:25:03 INFO - PROCESS | 1650 | RSS: Main: 340717568 20:25:03 INFO - PROCESS | 1650 | 20:28:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 20:28:07 INFO - PROCESS | 1650 | RSS: Main: 479145984 20:28:07 INFO - PROCESS | 1650 | 20:28:07 INFO - PROCESS | 1650 | __start_tp_report 20:28:07 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 20:28:07 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 20:28:07 INFO - PROCESS | 1650 | |i|pagename|runs| 20:28:07 INFO - PROCESS | 1650 | |0;dojo.html;2826.4064546445247;2832.5043117454447;3182.5226216067604;3291.7202037826783;3328.6172090209634;5450.945490545098;5550.222968784368;5556.666600003998;5561.749721262543;5631.63394379366;5156.664816786906;5588.692621905793;5677.347105082917;5696.344920334162;5735.799247026355;5128.71792051437;5180.326557547513;5211.947880521184;5232.084385232588;5276.984352490193;5251.76367063483;5273.736313184345;5292.788288468457;5297;5302.44324345942;5111.514406131411;5147.819826306099;5162.638615296931;5224.63427560071;5245.291885595434;5179.663321884065;5182.792688292464;5186.766595503173;5187.429382767892;5194.35070616173;5112.335396398482;5123.692578445305;5155.381354237478;5181.715005674687;5185.0148471790235;5595.468430499137;5671.659700417947;5717.028105222081;5749.367569567345;5787.074068149076;6104.175936248594;6191.628502289832;6202.503799696058;6232.1898153240245;6238.625682459022;6115;6314.62112273265;6326.778562750328;6340.556161068727;6348.968255158741;5653.491185793298;5669;5735.4551317624755;5759.337676167211;5839.474447299764;6306.716197771019;6327.651979141191;6336.52476064297;6339.809805705836;6342.23893132827;6089.573729838958;6103.95517501633;6106.7560289630255;6115.663638499926;6116.694165291807;6077.1491991121275;6150.753969841246;6204.441600255908;6220.7511699532415;6239.313675495692;6206.100115483229;6218.502519798405;6232.439080482778;6234.314225435199;6235.438810507076;6232.532560058014;6252.3122456529745;6272.843178920563;6274.121622972787;6277.18396608438;4886.438059623118;4965.875853103701;4971.45314015458;4972.975135124374;4986.4514903360605;4462.062966777014;4465.26323156682;4493.5281795411665;4497.865064048084;4507.301368287855;5583.972080139573;5662.716864156776;5665.065264231438;5671.971640141773;5685.914711279334;5558.805441809517;6239.188905442264;6252.656103914237;6275.6862156892885;6278.340774218733;5541.196526503634;5638.267025286768;5651.406602306781;5664.6034777566;5666.688332141772;5298;5376.1129413647095;5392.083345831218;5435.83692489226;5439.993601183714;6229.626222426669;6284.654344011124;6304.243490781136;6317.747290108528;6318.589291696117;6259.906101408392;6305.274893387319;6310.2743184534365;6316.684165791784;6336.936630633634;6203.534734894811;6238.407351301619;6267.46726528232;6288.811335659938;6296.275928268308;4866.756662166948;6159.353267906805;6180.351063138485;6206.441420272016;6219.564630475823;6217.564770466024;6245.594036387621;6250.249970003629;6254.687265636609;6298.11826344303;5911.497522710618;5962.940370596412;6124.724387402666;6134.202553667995;6144.2319710036245;6026.548008899438;6057.636541807506;6063.272407310975;6077.270727512727;6082.9695851520455;5792.333881603669;5924.940750592611;5939.346671866005;5978.790742324171;5990.34106248321;6101.7559297627595;6124.686092398567;6130.856783744145;6133.718460623545;6141.823559979257;5716.171155182392;5834.212381328636;5852.239208902815;5927.288725352985;5957.582969192288 20:28:07 INFO - PROCESS | 1650 | |1;ext.html;455.2738382280262;466.84827431084904;478.825228791491;505.24465923444444;514.2081194959762;30365;31884.840575797116;40388.394174087414;40487;40619.187616247735;34667.133321666974;41984;42283.36574951378;42383;42420.575794242046;9716.222702183808;9890.505474726271;9896.703098907008;9900.356476828983;9940.453275069904;8766.298696104297;8987.325950553703;11897.643070707849;12141;12194.93902530486;12172.965297949684;12249.93875030628;12258.63224103274;12262.325572093532;12360.381980900964;10476.895231047669;12109.697257568543;12273.95671367926;12294.99999999991;12318.952889004446;10199.847002294971;11995.100367472387;12077.939610301892;12079.516819327304;12087;39975.600243997476;40240.396394054114;40299.59700402988;40418.59581404207;40526.79736601328;3555.093451169961;3579.4451859961746;3679.1721862580707;3804.7907365094907;4080.9591904080876;31163.220919476968;32024.719011239522;32243.35513289721;32306.707731690705;32852.6714732852;3230.5800245967876;3364.142143753352;3406.0633325835347;3421.8973430797114;3429.8113603751785;444926;450449;451082;451154.2096026623;461435.9999999966;59606.40393596009;59892.40107598868;59923.99999999913;59974;60062;63291;63361.68319158467;63456.73086538244;63540;63621;13572.592822215349;16515.504534863972;16546;16550.83449165517;16555.420560280334;11554.017908477705;12741.426635801412;12849.48602055926;12857.549985750455;12892.935535322451;15270.465533706267;17381.565460863327;17434;17540.386086487168;18128.45614631563;5275.3026731130285;5458.372287187024;6466.870662586722;6486.124373209651;6507.624716978269;865.5542395666237;939.5771902643837;948.4025064209504;1014.4978235773192;1070.3898777696638;29957.550636740467;30346.937857174893;30617;30983.380332393226;31100.377992440477;914.8170365926841;1049.511976930731;1067.0876400677464;1098.7199912102312;1113.933164010162;15129.319180636898;15653.843461565239;15690.980086294354;15994.920025400032;16181.029138251743;654253;657466;672856;673587;700829;59015;60005.399946000856;60382;63872;64131.67934160393;1134.9943250283695;1348.191085348783;1384.3078460769616;1388.5556621881103;1391.2348208485496;30187.849060754557;30853.382932340777;31692.918305831867;31957.20106997344;32814.507782383764;54057.72971135119;54495.18257226065;54553.45446545642;54665.72667136639;54705;620076;626123;626322;626874;628778;36395;38771.83684489357;41170.58829411668;41630.583694163885;41872.3719144207;47948.28077578909;48227.276590850466;49120.2631960528;49386.01227975492;49868.25197621966;38061;40063;40085.6948289824;41398.37902431526;41793.79103104465;36988.81505592455;39516;39687.80156099201;41200.1759964805;41752.58247417609;38215.80892095522;39111;39198;39833.40249896196;39930;33017.834910826256;33300.33399331952;35244.47133292951;35520.934371968884;35666.64333356737;21972.935007023752;25300.367490812878;25379.6193057108;25680.743192567836;25850.177767832873 20:28:07 INFO - PROCESS | 1650 | |2;jquery.html;356.6700801758373;367.9558452985642;376.8662124945644;382.85451528419196;405.10067649817415;15119.47081852134;16683.416080437186;20813.583728325426;21310.89344553279;21414.25050123248;19643.214271429162;20190.495237619067;20358.389248322535;20684.689729654066;21104;5807.070868661015;6068.484178844804;6091.14723938649;7334.853302933938;7764.495307805003;6605.603663780164;7462.403007759393;7819.569923654197;7838.176991415905;7841.451098423113;7668.118166410851;7746.225377462237;7764.18476060014;7768.495047821873;7788.766337009871;7403.111626604815;7658.195889431613;7665.61671916402;7735.1491335953;7756.146823849372;6607.867842643121;7284.235155308722;7427.220141885078;7450.701971921171;7466.290702383265;28144.718552814415;28165.57751633727;28261.15216543507;28357;28830.990915318074;2239.4307241523707;2291.8051965582945;2305.7924786769104;2604.400987772804;2755.9035433759923;15364.769528457082;16558.089305088215;16702.749458758128;16724.58188545284;17191.398301059402;2010.6079314533672;2042.9897850510652;2091.309867743641;2104.0426605894436;2149.2370208575885;100878.98242035191;113788.99999999917;115770;116612.25081623867;130746.346268269;16491;19921.402357928997;20731.27440539574;21045.36863894055;21138;34324.48513272303;34348.141296467285;34362.48456273158;34391.140221494665;34432.311353772784;9961.153301969414;10036.799264014824;10264.743381415376;10265.486725663837;10362.948185259025;8426;8442.493450392996;8455.95772021136;8548.316134709208;8592.140785921358;9219.539023048821;9367;9445.513381143901;9460;9485.857712134324;3102.37952409519;3541.575010998645;3618.8552457901387;3631.7457777955287;3652.926941461156;832.7168762620616;856.905740368559;928.9489078100769;935.6584846531103;1051.5530899367739;20636.793632063487;21187;21204.893975530023;21323.68014479784;21469.248576299753;771.934385577221;935.5696379665294;944.4569372610639;950.2255661635745;1021.9437930913722;10401;11192.44037798107;11195.944020279847;11197.776044479066;11232.55069797199;591124;598789;606273;607028;608486.9575652094;113246;123892.38053809674;124005;126234;127382;965.6958058211782;968.7142293023575;996.2478328861808;999.1058003087196;999.5651891427252;18748.718769218747;19003.144858481126;19033;19067.046647667285;19159.137838797567;45822.77088614669;54853.35439936808;54947;55020.174697378774;58029.709851450476;82020;82374.76437853558;83636.58181709053;84290;84325;7770.145284018867;7882.802929926797;8088.9595552021865;8109.432339736162;8340.416170868224;11660.65018049426;12353.938230308791;12464.376781161087;12815.743685126432;12820.23078615286;9256;10862.480560260728;10952.34285942846;11085.833712494157;11249.775004500028;10684.626038088629;10709.839352409876;10936.125109991022;11044.613438529614;11061.059809916247;7840.411969102226;8370.330373570221;10297.588096476056;10545.420001899662;10805.891941080488;7696.384289256734;8067.394945379003;8080.353571714366;8255.25702686737;8270.545120018456;9440.197583205505;10387.68836934893;10802.027817496462;10806.945965270123;10814 20:28:07 INFO - PROCESS | 1650 | |3;mootools.html;1348.2651954684695;1489.441459452705;1513.319006447099;1653.4130383713782;1817.645559115972;7845.215478452152;8284.295834854038;8287.544185069819;8305.460145090572;8326.292265157468;8086.838263234732;8418.663253469855;8636.050034496217;8670.86993695095;8741.650333986632;2352.7294361148474;2378.797802186816;2385.451346190377;2385.654080158376;2389.7490763469846;2333.0900948630047;2342.9414264643433;2373;2378.452955820154;2382.1662418153683;2342.0982921575146;2370.466645004869;2370.8696021718883;2384.833061685683;2385.272491889973;2320.9419764505856;2336.5910965581043;2356.4815740537056;2358.811295096388;2371.1819422299277;2293.1629955066282;2314.201600447843;2345.1791872844537;2350.7531709170635;2353.411647088228;8623.439476434012;8720.738377848675;8738.956305218497;8755.824883502359;8802.119788021211;5158.2520534522655;5194.766235519411;5327.760250788724;5330.626856120073;5331.333583302087;3617.927641447183;3703.962960370389;3704.0369503928905;3705.0366904605;3706.425504046877;1101.8512500812449;1111.744298811278;1137.6416428824894;1140.076538004211;1148.0356500539503;11525.596604118899;11629.302241865431;11632.360220187886;11634;11634.534618615246;4262.381954616625;4558.521355257651;4613.192691279013;4616.930746038812;4623.791929363187;9112.271018318519;9113.3165012623;9121.817563648823;9130.497822619687;9157.404768690014;5231.764570594332;5235.267062611238;5237.004969055867;5238.476152384807;5247.422783493813;3648.069742215718;4364.75993820343;4443.2224360736745;4908.361912951294;4949.356583644175;3684.078980254936;3751.1372384351753;3820.8280627371278;3843.6348546888003;3860.517435320585;996.0139461932635;1157.9015783658315;1162.7093226693328;1165.0621249893748;1166.1312322319925;830.5216714248647;849.3163003781891;886.6674996876171;924.3102571516854;948.6300342866288;5688.374278829326;5818.243628328375;5876.353601103875;5974.850628734316;5997.564793877214;371.59310554942294;442.763121729873;451;454.56816024775935;457.5744557561433;3912.726109172331;4011.638952494231;4028.6978476613785;4129.380592911088;4177.310743727312;10653.147748180127;10698.465076746132;10826.18803589734;10832.458377081115;10870.456477176109;10255.487225638688;10457.42484163363;10710.982456666605;10720.517576708911;10802.837957430645;8024.839503209903;8068.3948703847445;8269.917300826915;8284.171582841787;8296.253337199681;5907.113932909926;5936.22829032223;6100.786472473442;6156.26124865019;6208.1308616792785;8082.7171049013;9780.657676981271;9859.30984831055;10029.14752246038;10110.69667909964;9821.754456138653;10163.593456261668;10179.389236645826;10261.84607230907;10274.897251027693;6330.461910737639;6973.12835895513;7513.8497230054;7561.054868141482;7589.354904833151;8213;8290.253877151084;8295.170482951657;8297.668093276201;8301.211384918422;7448.8882666758955;7515.398768098648;7524.736634217776;7661.578613176329;7662.501937374052;7336.523125996793;7393.9260607393235;7404.96297518509;7413.406927445899;7445.329920307198;6881.65591720402;7048.295170482911;7060.046893669494;7065;7077.079979602619;4889.975550122226;4989.176785830297;5007.824726134568;5024.598032157492;5138.280640710378;4935.235038569028;4941.802327906844;5133;5139.563137133386;5213.426523082382 20:28:07 INFO - PROCESS | 1650 | |4;prototype.html;297.1753384358407;303.1632693765208;311.3943380125656;314.818979087025;316.1290644275022;5160.561352285051;5338.225957236198;5362.705051222186;5402.865867982145;5405.243265942766;4200.180964711883;4522.4346956630425;4663.930041049387;5081.713229046934;5249.425172448269;3464.6881780639737;4083.836646534135;4104.281750693632;4110.671146308288;4113.9177216455655;3390.7287417006582;3957.7229593928437;4110.6094920982605;4118.773467459288;4123.608257215575;3121.797083189597;3989.4015897615445;4071.1654110907343;4086.693497987648;4090;3720.6279372062577;4026.9597304027025;4067.97966010171;4070.5725898780497;4082.8571000015004;3556.199855032623;3583.122135076897;3592.1738000259825;3593.1495279930596;3593.2274560969518;3857.594952529972;4792.9281060784115;5209.244659524347;5221.545873439477;5231.659942103751;4554.772261386917;4561.5175068103;4575.757878639476;4579.335996280554;4579.778807278467;4992.101421744084;5218.382301486551;5572.359178694462;5582.581306401996;5595.824876775882;3163.7186939289622;3490.2670439207795;3798.898319487373;3803.8478460861534;3841.519810023747;4889.046635906036;5128.3076784633595;5561.277033985596;5883.352831188566;5893.263342082239;3700.2784457031157;3857.363535016691;3896.2597106549665;3905.062784931596;3906.531216254011;3653.2328211075974;3924;3935.4490371348033;3956.7032472564674;3957.248122856648;4506.977465112654;4538.160440318552;4551.431071116111;4569;4574.7941342639;5132.8460146195675;5306.575473962073;5479.21925374255;5591.099026330986;5648.378678345379;1156.6472225970992;1271.510468469646;1286.343964578072;1298.5260379961246;1306.8627794081674;863.8488264553678;871.5947084605689;908.2143945487238;915.455304094063;915.9038300978434;4252.405347994502;5163.044836705221;5185.144451165591;5188.948110518922;5225;4736.5973892219545;4984.277279794482;5397.3523177218985;5466.840597963782;5577.414371491016;4052.756834589934;4479.484859241164;5055.039542486916;5107.284980102789;5118.564921981599;2281.9771802281766;2511.67348244727;2523.2682522068762;2546.22340186245;2558.577834657298;4421.7789110544345;4487.528809475023;4829.319727551173;4917.680565618193;4936.963237720047;3999.7000224983244;4132.586741325843;4276.679249056333;4283.87148385549;4844.539768721966;4435.802333369972;4622.907541849144;4803.95196048042;4825.538975122713;4915.926261106015;3833.5399752029934;3971.8411263549133;3990.521137463523;4368.9126217476105;4593.793279302507;3803.801802432225;4129.442525259052;4141;4718.905621887475;4736.644751643709;4872.366592342973;4887.772077153166;4906.362172917497;4910;5009.574186194214;2799.5380762174013;3224.935501290008;3296.9340613187123;3315.867365305457;3362.815045172441;4767.356406885026;4880.92678609814;5111.412187598474;5221.791128354823;5464.890702186013;4811.615070794258;5339.145736682112;5642.943570564406;5687.402822703557;5703.971480142573;5161.922571161361;5628.086450606814;5665.348484924287;5680.318361796611;5681.9431805681415;5212;5248.900439823949;5492.533134683436;5513.862153446196;5543.25166102571;5195.870103247449;5440.455954404528;5658.858528536654;5665.66006039639;5671.8865622688145;4935.6051515877925;5332.440093790251;5352.571794256372;5480.205370221375;5508.862278443071 20:28:07 INFO - PROCESS | 1650 | |5;yui.html;673.4376795375861;739.9075115610549;753.1827966656174;797.9880301795474;798.7763426240647;15074.553006862974;16432.260548275328;16440.917795411035;16461;16463.50609481716;14716.337764800583;14865.331060102295;14965.25173741314;15419.92290038551;16290.185490725446;862.090494528274;872.8428882801094;887.2148148888248;888.7778055486128;896.274018045382;877.1623099939575;880.3661363818072;880.8766772651834;880.9956649419667;882.0826340605762;730.5835673666013;932.752820502564;937.2689302344148;937.8593211018334;938.0994245524271;756.8788993761;888.1917455115878;926.7729406295433;927.2859897878595;931.1107891963159;670.5540815357781;671.8421171024805;673.636236432326;674.8447856992871;675.7060678604822;12304.138710290283;15227.923860380737;15265.923670381688;15274.847251527564;15289.923550382176;13089.214647121204;14556.636084097876;14573.927130364385;14852.925735371255;14874.851251487455;11759.647210583697;12042.09684273683;12354.505819767199;12361.69095772604;12627.242365458102;3888.9027774305587;4105.651019663303;4108.034611866209;4151.27352713274;4157.812898419578;118056.40971795085;122364;123043.38478307641;123316.9999999991;123379.3831030839;11365.943170284096;11496.712582185512;11538.192326537062;11996.64010079699;12219.144659873902;23944.40138996539;23960.64059039116;23988.88005559961;23990.760092399203;24010.639840402408;8026.357891368793;8044.517328960281;8069.354451643854;8207.507549547046;8222.342212622976;4251.489821221411;4275.940990621179;4335.393044973706;4338.587834155814;4461.08547747711;3693.4644476550748;3791.981040094837;3808.6953043756434;3810.714196435223;3817.1602247505502;655.5837043477426;668.2348710726192;681.7920534237105;683.1426559667585;683.8871586188322;568.2499101186477;639.1850390751829;645.8191706322237;693.5249354192293;696.9337912898267;8843.665848544682;9410.670626528039;9624.66313679018;9725.951370243103;9873.75315617101;502.43977964569973;593.2673148661399;593.7120496559165;614.8893199224183;665.218368417108;4461.308497182942;4552.408186935677;4781.545753153445;5087.135187018216;5216.765245563959;153081.23459382632;160472;161304;164920.3507964929;170603.14698426428;136366;138910.30544847212;139609.60390395965;140043;144999;91636;111906.32140517898;114939.42530287128;124622;127576.36211818882;9764.388408764391;9877.30858839903;10036.799264014533;10215.233857460571;10285.74285642865;54566.45433545614;56001.43998560125;58057;59040.704796475744;59854.40145598488;44761;72494.38808924987;76557.85163222445;86856.69714954155;94778;12984.415701293276;13062.608121756362;13392;13760.793588096387;13774.110161065977;13632.386542605409;14435.2060636666;15031.398744050115;15163.696726065638;15508.612284692974;13843.515476958259;14091.929540352232;14245.28773561297;14304.427822886968;14321.785173222619;11214.775704486028;13845.723085538435;14053.859461405254;14427.567172984827;14569.9801013928;7693.538387696756;13594.048416610742;13705.588832335046;14207.573772786833;14271.357788899317;10749.785004299714;11037.668869933916;11131.387773672526;11282.153838462313;11980.28118312904;11154.372198428482;11303.378314192798;11330.94334528322;11617.361045142261;11707.88292117102 20:28:07 INFO - PROCESS | 1650 | __end_tp_report 20:28:07 INFO - PROCESS | 1650 | __start_cc_report 20:28:07 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1388 20:28:07 INFO - PROCESS | 1650 | __end_cc_report 20:28:07 INFO - PROCESS | 1650 | __startTimestamp1471058887458__endTimestamp 20:28:07 INFO - PROCESS | 1650 | 20:28:07 INFO - PROCESS | 1650 | ------- Summary: start ------- 20:28:07 INFO - PROCESS | 1650 | Number of tests: 6 20:28:07 INFO - PROCESS | 1650 | 20:28:07 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5713.33 Median:5924.94 stddev:671.56 (11.3%) stddev-sans-first:634.29 20:28:07 INFO - PROCESS | 1650 | Values: 2826.4 2832.5 3182.5 3291.7 3328.6 5450.9 5550.2 5556.7 5561.7 5631.6 5156.7 5588.7 5677.3 5696.3 5735.8 5128.7 5180.3 5211.9 5232.1 5277.0 5251.8 5273.7 5292.8 5297.0 5302.4 5111.5 5147.8 5162.6 5224.6 5245.3 5179.7 5182.8 5186.8 5187.4 5194.4 5112.3 5123.7 5155.4 5181.7 5185.0 5595.5 5671.7 5717.0 5749.4 5787.1 6104.2 6191.6 6202.5 6232.2 6238.6 6115.0 6314.6 6326.8 6340.6 6349.0 5653.5 5669.0 5735.5 5759.3 5839.5 6306.7 6327.7 6336.5 6339.8 6342.2 6089.6 6104.0 6106.8 6115.7 6116.7 6077.1 6150.8 6204.4 6220.8 6239.3 6206.1 6218.5 6232.4 6234.3 6235.4 6232.5 6252.3 6272.8 6274.1 6277.2 4886.4 4965.9 4971.5 4973.0 4986.5 4462.1 4465.3 4493.5 4497.9 4507.3 5584.0 5662.7 5665.1 5672.0 5685.9 5558.8 6239.2 6252.7 6275.7 6278.3 5541.2 5638.3 5651.4 5664.6 5666.7 5298.0 5376.1 5392.1 5435.8 5440.0 6229.6 6284.7 6304.2 6317.7 6318.6 6259.9 6305.3 6310.3 6316.7 6336.9 6203.5 6238.4 6267.5 6288.8 6296.3 4866.8 6159.4 6180.4 6206.4 6219.6 6217.6 6245.6 6250.2 6254.7 6298.1 5911.5 5962.9 6124.7 6134.2 6144.2 6026.5 6057.6 6063.3 6077.3 6083.0 5792.3 5924.9 5939.3 5978.8 5990.3 6101.8 6124.7 6130.9 6133.7 6141.8 5716.2 5834.2 5852.2 5927.3 5957.6 20:28:07 INFO - PROCESS | 1650 | 20:28:07 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:72308.42 Median:38941.42 stddev:157952.41 (405.6%) stddev-sans-first:158303.38 20:28:07 INFO - PROCESS | 1650 | Values: 455.3 466.8 478.8 505.2 514.2 30365.0 31884.8 40388.4 40487.0 40619.2 34667.1 41984.0 42283.4 42383.0 42420.6 9716.2 9890.5 9896.7 9900.4 9940.5 8766.3 8987.3 11897.6 12141.0 12194.9 12173.0 12249.9 12258.6 12262.3 12360.4 10476.9 12109.7 12274.0 12295.0 12319.0 10199.8 11995.1 12077.9 12079.5 12087.0 39975.6 40240.4 40299.6 40418.6 40526.8 3555.1 3579.4 3679.2 3804.8 4081.0 31163.2 32024.7 32243.4 32306.7 32852.7 3230.6 3364.1 3406.1 3421.9 3429.8 444926.0 450449.0 451082.0 451154.2 461436.0 59606.4 59892.4 59924.0 59974.0 60062.0 63291.0 63361.7 63456.7 63540.0 63621.0 13572.6 16515.5 16546.0 16550.8 16555.4 11554.0 12741.4 12849.5 12857.5 12892.9 15270.5 17381.6 17434.0 17540.4 18128.5 5275.3 5458.4 6466.9 6486.1 6507.6 865.6 939.6 948.4 1014.5 1070.4 29957.6 30346.9 30617.0 30983.4 31100.4 914.8 1049.5 1067.1 1098.7 1113.9 15129.3 15653.8 15691.0 15994.9 16181.0 654253.0 657466.0 672856.0 673587.0 700829.0 59015.0 60005.4 60382.0 63872.0 64131.7 1135.0 1348.2 1384.3 1388.6 1391.2 30187.8 30853.4 31692.9 31957.2 32814.5 54057.7 54495.2 54553.5 54665.7 54705.0 620076.0 626123.0 626322.0 626874.0 628778.0 36395.0 38771.8 41170.6 41630.6 41872.4 47948.3 48227.3 49120.3 49386.0 49868.3 38061.0 40063.0 40085.7 41398.4 41793.8 36988.8 39516.0 39687.8 41200.2 41752.6 38215.8 39111.0 39198.0 39833.4 39930.0 33017.8 33300.3 35244.5 35520.9 35666.6 21972.9 25300.4 25379.6 25680.7 25850.2 20:28:07 INFO - PROCESS | 1650 | 20:28:07 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:36496.76 Median:34376.81 stddev:100138.92 (291.3%) stddev-sans-first:100383.07 20:28:07 INFO - PROCESS | 1650 | Values: 356.7 368.0 376.9 382.9 405.1 15119.5 16683.4 20813.6 21310.9 21414.3 19643.2 20190.5 20358.4 20684.7 21104.0 5807.1 6068.5 6091.1 7334.9 7764.5 6605.6 7462.4 7819.6 7838.2 7841.5 7668.1 7746.2 7764.2 7768.5 7788.8 7403.1 7658.2 7665.6 7735.1 7756.1 6607.9 7284.2 7427.2 7450.7 7466.3 28144.7 28165.6 28261.2 28357.0 28831.0 2239.4 2291.8 2305.8 2604.4 2755.9 15364.8 16558.1 16702.7 16724.6 17191.4 2010.6 2043.0 2091.3 2104.0 2149.2 100879.0 113789.0 115770.0 116612.3 130746.3 16491.0 19921.4 20731.3 21045.4 21138.0 34324.5 34348.1 34362.5 34391.1 34432.3 9961.2 10036.8 10264.7 10265.5 10362.9 8426.0 8442.5 8456.0 8548.3 8592.1 9219.5 9367.0 9445.5 9460.0 9485.9 3102.4 3541.6 3618.9 3631.7 3652.9 832.7 856.9 928.9 935.7 1051.6 20636.8 21187.0 21204.9 21323.7 21469.2 771.9 935.6 944.5 950.2 1021.9 10401.0 11192.4 11195.9 11197.8 11232.6 591124.0 598789.0 606273.0 607028.0 608487.0 113246.0 123892.4 124005.0 126234.0 127382.0 965.7 968.7 996.2 999.1 999.6 18748.7 19003.1 19033.0 19067.0 19159.1 45822.8 54853.4 54947.0 55020.2 58029.7 82020.0 82374.8 83636.6 84290.0 84325.0 7770.1 7882.8 8089.0 8109.4 8340.4 11660.7 12353.9 12464.4 12815.7 12820.2 9256.0 10862.5 10952.3 11085.8 11249.8 10684.6 10709.8 10936.1 11044.6 11061.1 7840.4 8370.3 10297.6 10545.4 10805.9 7696.4 8067.4 8080.4 8255.3 8270.5 9440.2 10387.7 10802.0 10806.9 10814.0 20:28:07 INFO - PROCESS | 1650 | 20:28:07 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5636.18 Median:4938.52 stddev:3167.43 (64.1%) stddev-sans-first:3159.92 20:28:07 INFO - PROCESS | 1650 | Values: 1348.3 1489.4 1513.3 1653.4 1817.6 7845.2 8284.3 8287.5 8305.5 8326.3 8086.8 8418.7 8636.1 8670.9 8741.7 2352.7 2378.8 2385.5 2385.7 2389.7 2333.1 2342.9 2373.0 2378.5 2382.2 2342.1 2370.5 2370.9 2384.8 2385.3 2320.9 2336.6 2356.5 2358.8 2371.2 2293.2 2314.2 2345.2 2350.8 2353.4 8623.4 8720.7 8739.0 8755.8 8802.1 5158.3 5194.8 5327.8 5330.6 5331.3 3617.9 3704.0 3704.0 3705.0 3706.4 1101.9 1111.7 1137.6 1140.1 1148.0 11525.6 11629.3 11632.4 11634.0 11634.5 4262.4 4558.5 4613.2 4616.9 4623.8 9112.3 9113.3 9121.8 9130.5 9157.4 5231.8 5235.3 5237.0 5238.5 5247.4 3648.1 4364.8 4443.2 4908.4 4949.4 3684.1 3751.1 3820.8 3843.6 3860.5 996.0 1157.9 1162.7 1165.1 1166.1 830.5 849.3 886.7 924.3 948.6 5688.4 5818.2 5876.4 5974.9 5997.6 371.6 442.8 451.0 454.6 457.6 3912.7 4011.6 4028.7 4129.4 4177.3 10653.1 10698.5 10826.2 10832.5 10870.5 10255.5 10457.4 10711.0 10720.5 10802.8 8024.8 8068.4 8269.9 8284.2 8296.3 5907.1 5936.2 6100.8 6156.3 6208.1 8082.7 9780.7 9859.3 10029.1 10110.7 9821.8 10163.6 10179.4 10261.8 10274.9 6330.5 6973.1 7513.8 7561.1 7589.4 8213.0 8290.3 8295.2 8297.7 8301.2 7448.9 7515.4 7524.7 7661.6 7662.5 7336.5 7393.9 7405.0 7413.4 7445.3 6881.7 7048.3 7060.0 7065.0 7077.1 4890.0 4989.2 5007.8 5024.6 5138.3 4935.2 4941.8 5133.0 5139.6 5213.4 20:28:07 INFO - PROCESS | 1650 | 20:28:07 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4269.12 Median:4608.35 stddev:1300.02 (28.2%) stddev-sans-first:1269.02 20:28:07 INFO - PROCESS | 1650 | Values: 297.2 303.2 311.4 314.8 316.1 5160.6 5338.2 5362.7 5402.9 5405.2 4200.2 4522.4 4663.9 5081.7 5249.4 3464.7 4083.8 4104.3 4110.7 4113.9 3390.7 3957.7 4110.6 4118.8 4123.6 3121.8 3989.4 4071.2 4086.7 4090.0 3720.6 4027.0 4068.0 4070.6 4082.9 3556.2 3583.1 3592.2 3593.1 3593.2 3857.6 4792.9 5209.2 5221.5 5231.7 4554.8 4561.5 4575.8 4579.3 4579.8 4992.1 5218.4 5572.4 5582.6 5595.8 3163.7 3490.3 3798.9 3803.8 3841.5 4889.0 5128.3 5561.3 5883.4 5893.3 3700.3 3857.4 3896.3 3905.1 3906.5 3653.2 3924.0 3935.4 3956.7 3957.2 4507.0 4538.2 4551.4 4569.0 4574.8 5132.8 5306.6 5479.2 5591.1 5648.4 1156.6 1271.5 1286.3 1298.5 1306.9 863.8 871.6 908.2 915.5 915.9 4252.4 5163.0 5185.1 5188.9 5225.0 4736.6 4984.3 5397.4 5466.8 5577.4 4052.8 4479.5 5055.0 5107.3 5118.6 2282.0 2511.7 2523.3 2546.2 2558.6 4421.8 4487.5 4829.3 4917.7 4937.0 3999.7 4132.6 4276.7 4283.9 4844.5 4435.8 4622.9 4804.0 4825.5 4915.9 3833.5 3971.8 3990.5 4368.9 4593.8 3803.8 4129.4 4141.0 4718.9 4736.6 4872.4 4887.8 4906.4 4910.0 5009.6 2799.5 3224.9 3296.9 3315.9 3362.8 4767.4 4880.9 5111.4 5221.8 5464.9 4811.6 5339.1 5642.9 5687.4 5704.0 5161.9 5628.1 5665.3 5680.3 5681.9 5212.0 5248.9 5492.5 5513.9 5543.3 5195.9 5440.5 5658.9 5665.7 5671.9 4935.6 5332.4 5352.6 5480.2 5508.9 20:28:07 INFO - PROCESS | 1650 | 20:28:07 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:25613.33 Median:13852.05 stddev:41928.94 (302.7%) stddev-sans-first:42004.74 20:28:07 INFO - PROCESS | 1650 | Values: 673.4 739.9 753.2 798.0 798.8 15074.6 16432.3 16440.9 16461.0 16463.5 14716.3 14865.3 14965.3 15419.9 16290.2 862.1 872.8 887.2 888.8 896.3 877.2 880.4 880.9 881.0 882.1 730.6 932.8 937.3 937.9 938.1 756.9 888.2 926.8 927.3 931.1 670.6 671.8 673.6 674.8 675.7 12304.1 15227.9 15265.9 15274.8 15289.9 13089.2 14556.6 14573.9 14852.9 14874.9 11759.6 12042.1 12354.5 12361.7 12627.2 3888.9 4105.7 4108.0 4151.3 4157.8 118056.4 122364.0 123043.4 123317.0 123379.4 11365.9 11496.7 11538.2 11996.6 12219.1 23944.4 23960.6 23988.9 23990.8 24010.6 8026.4 8044.5 8069.4 8207.5 8222.3 4251.5 4275.9 4335.4 4338.6 4461.1 3693.5 3792.0 3808.7 3810.7 3817.2 655.6 668.2 681.8 683.1 683.9 568.2 639.2 645.8 693.5 696.9 8843.7 9410.7 9624.7 9726.0 9873.8 502.4 593.3 593.7 614.9 665.2 4461.3 4552.4 4781.5 5087.1 5216.8 153081.2 160472.0 161304.0 164920.4 170603.1 136366.0 138910.3 139609.6 140043.0 144999.0 91636.0 111906.3 114939.4 124622.0 127576.4 9764.4 9877.3 10036.8 10215.2 10285.7 54566.5 56001.4 58057.0 59040.7 59854.4 44761.0 72494.4 76557.9 86856.7 94778.0 12984.4 13062.6 13392.0 13760.8 13774.1 13632.4 14435.2 15031.4 15163.7 15508.6 13843.5 14091.9 14245.3 14304.4 14321.8 11214.8 13845.7 14053.9 14427.6 14570.0 7693.5 13594.0 13705.6 14207.6 14271.4 10749.8 11037.7 11131.4 11282.2 11980.3 11154.4 11303.4 11330.9 11617.4 11707.9 20:28:07 INFO - PROCESS | 1650 | -------- Summary: end -------- 20:28:07 INFO - PROCESS | 1650 | 20:28:08 INFO - TEST-INFO | 1650: exit 0 20:28:08 INFO - TEST-OK | dromaeo_css | took 1117956ms 20:28:08 INFO - TEST-START | kraken 20:28:08 INFO - operating with platform_type : mac_ 20:28:08 INFO - Initialising browser for kraken test... 20:28:08 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpv8GWfz/profile http://localhost:49268/getInfo.html) 20:28:10 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 20:28:10 INFO - PROCESS | 1659 | colorDepth:24 20:28:10 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 20:28:10 INFO - PROCESS | 1659 | __metrics 20:28:10 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" 20:28:10 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:28:11 INFO - TEST-INFO | 1659: exit 0 20:28:11 INFO - Browser initialized. 20:28:11 INFO - Running cycle 1/1 for kraken test... 20:28:11 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/T/tmpv8GWfz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:28:23 INFO - PROCESS | 1660 | RSS: Main: 228511744 20:28:23 INFO - PROCESS | 1660 | 20:28:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 20:28:26 INFO - PROCESS | 1660 | RSS: Main: 285229056 20:28:26 INFO - PROCESS | 1660 | 20:28:29 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 20:28:29 INFO - PROCESS | 1660 | RSS: Main: 294182912 20:28:29 INFO - PROCESS | 1660 | 20:28:33 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 20:28:33 INFO - PROCESS | 1660 | RSS: Main: 385232896 20:28:33 INFO - PROCESS | 1660 | 20:28:35 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 20:28:36 INFO - PROCESS | 1660 | RSS: Main: 289554432 20:28:36 INFO - PROCESS | 1660 | 20:28:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:28:39 INFO - PROCESS | 1660 | RSS: Main: 294268928 20:28:39 INFO - PROCESS | 1660 | 20:28:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 20:28:42 INFO - PROCESS | 1660 | RSS: Main: 330051584 20:28:42 INFO - PROCESS | 1660 | 20:28:45 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 20:28:46 INFO - PROCESS | 1660 | RSS: Main: 331911168 20:28:46 INFO - PROCESS | 1660 | 20:28:48 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 20:28:49 INFO - PROCESS | 1660 | RSS: Main: 329342976 20:28:49 INFO - PROCESS | 1660 | 20:28:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:28:51 INFO - PROCESS | 1660 | RSS: Main: 301953024 20:28:51 INFO - PROCESS | 1660 | 20:28:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:28:54 INFO - PROCESS | 1660 | RSS: Main: 308158464 20:28:54 INFO - PROCESS | 1660 | 20:28:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:28:57 INFO - PROCESS | 1660 | RSS: Main: 310710272 20:28:57 INFO - PROCESS | 1660 | 20:29:00 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:29:00 INFO - PROCESS | 1660 | RSS: Main: 315994112 20:29:00 INFO - PROCESS | 1660 | 20:29:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:29:04 INFO - PROCESS | 1660 | RSS: Main: 318607360 20:29:04 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 20:29:06 INFO - PROCESS | 1660 | RSS: Main: 361984000 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | __start_tp_report 20:29:06 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 20:29:06 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 20:29:06 INFO - PROCESS | 1660 | |i|pagename|runs| 20:29:06 INFO - PROCESS | 1660 | |0;ai-astar;103;115;113;114;108;114;113;113;113;114 20:29:06 INFO - PROCESS | 1660 | |1;audio-beat-detection;141;109;89;197;139;135;129;107;153;113 20:29:06 INFO - PROCESS | 1660 | |2;audio-dft;184;182;183;182;185;184;179;182;190;182 20:29:06 INFO - PROCESS | 1660 | |3;audio-fft;77;57;61;86;58;57;63;61;82;57 20:29:06 INFO - PROCESS | 1660 | |4;audio-oscillator;87;70;102;71;70;69;95;69;68;102 20:29:06 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;86;84;87;86;88;84;88;85;87;84 20:29:06 INFO - PROCESS | 1660 | |6;imaging-darkroom;93;93;92;93;93;92;120;150;94;93 20:29:06 INFO - PROCESS | 1660 | |7;imaging-desaturate;84;86;83;86;85;85;86;86;82;85 20:29:06 INFO - PROCESS | 1660 | |8;json-parse-financial;51;51;53;54;51;53;54;53;53;55 20:29:06 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;64;73;89;108;111;76;69;73;69;70 20:29:06 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;89;88;85;86;84;86;84;87;90;87 20:29:06 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;139;139;133;137;136;143;153;144;228;137 20:29:06 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;168;161;168;165;166;166;165;170;165;184 20:29:06 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;67;68;71;65;87;65;67;68;66;68 20:29:06 INFO - PROCESS | 1660 | __end_tp_report 20:29:06 INFO - PROCESS | 1660 | __start_cc_report 20:29:06 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2054 20:29:06 INFO - PROCESS | 1660 | __end_cc_report 20:29:06 INFO - PROCESS | 1660 | __startTimestamp1471058946305__endTimestamp 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | ------- Summary: start ------- 20:29:06 INFO - PROCESS | 1660 | Number of tests: 14 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.50 stddev:3.68 (3.2%) stddev-sans-first:2.00 20:29:06 INFO - PROCESS | 1660 | Values: 103.0 115.0 113.0 114.0 108.0 114.0 113.0 113.0 113.0 114.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:140.00 stddev:30.13 (21.5%) stddev-sans-first:31.75 20:29:06 INFO - PROCESS | 1660 | Values: 141.0 109.0 89.0 197.0 139.0 135.0 129.0 107.0 153.0 113.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:2.87 (1.6%) stddev-sans-first:3.03 20:29:06 INFO - PROCESS | 1660 | Values: 184.0 182.0 183.0 182.0 185.0 184.0 179.0 182.0 190.0 182.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:65.90 Median:62.00 stddev:11.27 (18.2%) stddev-sans-first:11.21 20:29:06 INFO - PROCESS | 1660 | Values: 77.0 57.0 61.0 86.0 58.0 57.0 63.0 61.0 82.0 57.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:70.00 stddev:14.56 (20.8%) stddev-sans-first:15.24 20:29:06 INFO - PROCESS | 1660 | Values: 87.0 70.0 102.0 71.0 70.0 69.0 95.0 69.0 68.0 102.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:86.50 stddev:1.60 (1.8%) stddev-sans-first:1.69 20:29:06 INFO - PROCESS | 1660 | Values: 86.0 84.0 87.0 86.0 88.0 84.0 88.0 85.0 87.0 84.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:93.00 stddev:19.13 (20.6%) stddev-sans-first:20.05 20:29:06 INFO - PROCESS | 1660 | Values: 93.0 93.0 92.0 93.0 93.0 92.0 120.0 150.0 94.0 93.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.40 (1.6%) stddev-sans-first:1.45 20:29:06 INFO - PROCESS | 1660 | Values: 84.0 86.0 83.0 86.0 85.0 85.0 86.0 86.0 82.0 85.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.40 (2.6%) stddev-sans-first:1.32 20:29:06 INFO - PROCESS | 1660 | Values: 51.0 51.0 53.0 54.0 51.0 53.0 54.0 53.0 53.0 55.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:80.20 Median:71.50 stddev:16.79 (23.5%) stddev-sans-first:16.76 20:29:06 INFO - PROCESS | 1660 | Values: 64.0 73.0 89.0 108.0 111.0 76.0 69.0 73.0 69.0 70.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.00 stddev:2.01 (2.3%) stddev-sans-first:1.94 20:29:06 INFO - PROCESS | 1660 | Values: 89.0 88.0 85.0 86.0 84.0 86.0 84.0 87.0 90.0 87.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:141.00 stddev:28.34 (20.1%) stddev-sans-first:29.84 20:29:06 INFO - PROCESS | 1660 | Values: 139.0 139.0 133.0 137.0 136.0 143.0 153.0 144.0 228.0 137.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:167.00 stddev:6.18 (3.7%) stddev-sans-first:6.55 20:29:06 INFO - PROCESS | 1660 | Values: 168.0 161.0 168.0 165.0 166.0 166.0 165.0 170.0 165.0 184.0 20:29:06 INFO - PROCESS | 1660 | 20:29:06 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:68.00 stddev:6.49 (9.6%) stddev-sans-first:6.84 20:29:06 INFO - PROCESS | 1660 | Values: 67.0 68.0 71.0 65.0 87.0 65.0 67.0 68.0 66.0 68.0 20:29:06 INFO - PROCESS | 1660 | -------- Summary: end -------- 20:29:06 INFO - PROCESS | 1660 | 20:29:07 INFO - TEST-INFO | 1660: exit 0 20:29:07 INFO - TEST-OK | kraken | took 59198ms 20:29:07 INFO - SUITE-END | took 1177s 20:29:07 INFO - Completed test suite (00:19:37) 20:29:07 INFO - javascript benchmark 20:29:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2826.4064546445247, 2832.5043117454447, 3182.5226216067604, 3291.7202037826783, 3328.6172090209634, 5450.945490545098, 5550.222968784368, 5556.666600003998, 5561.749721262543, 5631.63394379366, 5156.664816786906, 5588.692621905793, 5677.347105082917, 5696.344920334162, 5735.799247026355, 5128.71792051437, 5180.326557547513, 5211.947880521184, 5232.084385232588, 5276.984352490193, 5251.76367063483, 5273.736313184345, 5292.788288468457, 5297.0, 5302.44324345942, 5111.514406131411, 5147.819826306099, 5162.638615296931, 5224.63427560071, 5245.291885595434, 5179.663321884065, 5182.792688292464, 5186.766595503173, 5187.429382767892, 5194.35070616173, 5112.335396398482, 5123.692578445305, 5155.381354237478, 5181.715005674687, 5185.0148471790235, 5595.468430499137, 5671.659700417947, 5717.028105222081, 5749.367569567345, 5787.074068149076, 6104.175936248594, 6191.628502289832, 6202.503799696058, 6232.1898153240245, 6238.625682459022, 6115.0, 6314.62112273265, 6326.778562750328, 6340.556161068727, 6348.968255158741, 5653.491185793298, 5669.0, 5735.4551317624755, 5759.337676167211, 5839.474447299764, 6306.716197771019, 6327.651979141191, 6336.52476064297, 6339.809805705836, 6342.23893132827, 6089.573729838958, 6103.95517501633, 6106.7560289630255, 6115.663638499926, 6116.694165291807, 6077.1491991121275, 6150.753969841246, 6204.441600255908, 6220.7511699532415, 6239.313675495692, 6206.100115483229, 6218.502519798405, 6232.439080482778, 6234.314225435199, 6235.438810507076, 6232.532560058014, 6252.3122456529745, 6272.843178920563, 6274.121622972787, 6277.18396608438, 4886.438059623118, 4965.875853103701, 4971.45314015458, 4972.975135124374, 4986.4514903360605, 4462.062966777014, 4465.26323156682, 4493.5281795411665, 4497.865064048084, 4507.301368287855, 5583.972080139573, 5662.716864156776, 5665.065264231438, 5671.971640141773, 5685.914711279334, 5558.805441809517, 6239.188905442264, 6252.656103914237, 6275.6862156892885, 6278.340774218733, 5541.196526503634, 5638.267025286768, 5651.406602306781, 5664.6034777566, 5666.688332141772, 5298.0, 5376.1129413647095, 5392.083345831218, 5435.83692489226, 5439.993601183714, 6229.626222426669, 6284.654344011124, 6304.243490781136, 6317.747290108528, 6318.589291696117, 6259.906101408392, 6305.274893387319, 6310.2743184534365, 6316.684165791784, 6336.936630633634, 6203.534734894811, 6238.407351301619, 6267.46726528232, 6288.811335659938, 6296.275928268308, 4866.756662166948, 6159.353267906805, 6180.351063138485, 6206.441420272016, 6219.564630475823, 6217.564770466024, 6245.594036387621, 6250.249970003629, 6254.687265636609, 6298.11826344303, 5911.497522710618, 5962.940370596412, 6124.724387402666, 6134.202553667995, 6144.2319710036245, 6026.548008899438, 6057.636541807506, 6063.272407310975, 6077.270727512727, 6082.9695851520455, 5792.333881603669, 5924.940750592611, 5939.346671866005, 5978.790742324171, 5990.34106248321, 6101.7559297627595, 6124.686092398567, 6130.856783744145, 6133.718460623545, 6141.823559979257, 5716.171155182392, 5834.212381328636, 5852.239208902815, 5927.288725352985, 5957.582969192288], "value": 5666.533852870994, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.2738382280262, 466.84827431084904, 478.825228791491, 505.24465923444444, 514.2081194959762, 30365.0, 31884.840575797116, 40388.394174087414, 40487.0, 40619.187616247735, 34667.133321666974, 41984.0, 42283.36574951378, 42383.0, 42420.575794242046, 9716.222702183808, 9890.505474726271, 9896.703098907008, 9900.356476828983, 9940.453275069904, 8766.298696104297, 8987.325950553703, 11897.643070707849, 12141.0, 12194.93902530486, 12172.965297949684, 12249.93875030628, 12258.63224103274, 12262.325572093532, 12360.381980900964, 10476.895231047669, 12109.697257568543, 12273.95671367926, 12294.99999999991, 12318.952889004446, 10199.847002294971, 11995.100367472387, 12077.939610301892, 12079.516819327304, 12087.0, 39975.600243997476, 40240.396394054114, 40299.59700402988, 40418.59581404207, 40526.79736601328, 3555.093451169961, 3579.4451859961746, 3679.1721862580707, 3804.7907365094907, 4080.9591904080876, 31163.220919476968, 32024.719011239522, 32243.35513289721, 32306.707731690705, 32852.6714732852, 3230.5800245967876, 3364.142143753352, 3406.0633325835347, 3421.8973430797114, 3429.8113603751785, 444926.0, 450449.0, 451082.0, 451154.2096026623, 461435.9999999966, 59606.40393596009, 59892.40107598868, 59923.99999999913, 59974.0, 60062.0, 63291.0, 63361.68319158467, 63456.73086538244, 63540.0, 63621.0, 13572.592822215349, 16515.504534863972, 16546.0, 16550.83449165517, 16555.420560280334, 11554.017908477705, 12741.426635801412, 12849.48602055926, 12857.549985750455, 12892.935535322451, 15270.465533706267, 17381.565460863327, 17434.0, 17540.386086487168, 18128.45614631563, 5275.3026731130285, 5458.372287187024, 6466.870662586722, 6486.124373209651, 6507.624716978269, 865.5542395666237, 939.5771902643837, 948.4025064209504, 1014.4978235773192, 1070.3898777696638, 29957.550636740467, 30346.937857174893, 30617.0, 30983.380332393226, 31100.377992440477, 914.8170365926841, 1049.511976930731, 1067.0876400677464, 1098.7199912102312, 1113.933164010162, 15129.319180636898, 15653.843461565239, 15690.980086294354, 15994.920025400032, 16181.029138251743, 654253.0, 657466.0, 672856.0, 673587.0, 700829.0, 59015.0, 60005.399946000856, 60382.0, 63872.0, 64131.67934160393, 1134.9943250283695, 1348.191085348783, 1384.3078460769616, 1388.5556621881103, 1391.2348208485496, 30187.849060754557, 30853.382932340777, 31692.918305831867, 31957.20106997344, 32814.507782383764, 54057.72971135119, 54495.18257226065, 54553.45446545642, 54665.72667136639, 54705.0, 620076.0, 626123.0, 626322.0, 626874.0, 628778.0, 36395.0, 38771.83684489357, 41170.58829411668, 41630.583694163885, 41872.3719144207, 47948.28077578909, 48227.276590850466, 49120.2631960528, 49386.01227975492, 49868.25197621966, 38061.0, 40063.0, 40085.6948289824, 41398.37902431526, 41793.79103104465, 36988.81505592455, 39516.0, 39687.80156099201, 41200.1759964805, 41752.58247417609, 38215.80892095522, 39111.0, 39198.0, 39833.40249896196, 39930.0, 33017.834910826256, 33300.33399331952, 35244.47133292951, 35520.934371968884, 35666.64333356737, 21972.935007023752, 25300.367490812878, 25379.6193057108, 25680.743192567836, 25850.177767832873], "value": 20693.40688754633, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.6700801758373, 367.9558452985642, 376.8662124945644, 382.85451528419196, 405.10067649817415, 15119.47081852134, 16683.416080437186, 20813.583728325426, 21310.89344553279, 21414.25050123248, 19643.214271429162, 20190.495237619067, 20358.389248322535, 20684.689729654066, 21104.0, 5807.070868661015, 6068.484178844804, 6091.14723938649, 7334.853302933938, 7764.495307805003, 6605.603663780164, 7462.403007759393, 7819.569923654197, 7838.176991415905, 7841.451098423113, 7668.118166410851, 7746.225377462237, 7764.18476060014, 7768.495047821873, 7788.766337009871, 7403.111626604815, 7658.195889431613, 7665.61671916402, 7735.1491335953, 7756.146823849372, 6607.867842643121, 7284.235155308722, 7427.220141885078, 7450.701971921171, 7466.290702383265, 28144.718552814415, 28165.57751633727, 28261.15216543507, 28357.0, 28830.990915318074, 2239.4307241523707, 2291.8051965582945, 2305.7924786769104, 2604.400987772804, 2755.9035433759923, 15364.769528457082, 16558.089305088215, 16702.749458758128, 16724.58188545284, 17191.398301059402, 2010.6079314533672, 2042.9897850510652, 2091.309867743641, 2104.0426605894436, 2149.2370208575885, 100878.98242035191, 113788.99999999917, 115770.0, 116612.25081623867, 130746.346268269, 16491.0, 19921.402357928997, 20731.27440539574, 21045.36863894055, 21138.0, 34324.48513272303, 34348.141296467285, 34362.48456273158, 34391.140221494665, 34432.311353772784, 9961.153301969414, 10036.799264014824, 10264.743381415376, 10265.486725663837, 10362.948185259025, 8426.0, 8442.493450392996, 8455.95772021136, 8548.316134709208, 8592.140785921358, 9219.539023048821, 9367.0, 9445.513381143901, 9460.0, 9485.857712134324, 3102.37952409519, 3541.575010998645, 3618.8552457901387, 3631.7457777955287, 3652.926941461156, 832.7168762620616, 856.905740368559, 928.9489078100769, 935.6584846531103, 1051.5530899367739, 20636.793632063487, 21187.0, 21204.893975530023, 21323.68014479784, 21469.248576299753, 771.934385577221, 935.5696379665294, 944.4569372610639, 950.2255661635745, 1021.9437930913722, 10401.0, 11192.44037798107, 11195.944020279847, 11197.776044479066, 11232.55069797199, 591124.0, 598789.0, 606273.0, 607028.0, 608486.9575652094, 113246.0, 123892.38053809674, 124005.0, 126234.0, 127382.0, 965.6958058211782, 968.7142293023575, 996.2478328861808, 999.1058003087196, 999.5651891427252, 18748.718769218747, 19003.144858481126, 19033.0, 19067.046647667285, 19159.137838797567, 45822.77088614669, 54853.35439936808, 54947.0, 55020.174697378774, 58029.709851450476, 82020.0, 82374.76437853558, 83636.58181709053, 84290.0, 84325.0, 7770.145284018867, 7882.802929926797, 8088.9595552021865, 8109.432339736162, 8340.416170868224, 11660.65018049426, 12353.938230308791, 12464.376781161087, 12815.743685126432, 12820.23078615286, 9256.0, 10862.480560260728, 10952.34285942846, 11085.833712494157, 11249.775004500028, 10684.626038088629, 10709.839352409876, 10936.125109991022, 11044.613438529614, 11061.059809916247, 7840.411969102226, 8370.330373570221, 10297.588096476056, 10545.420001899662, 10805.891941080488, 7696.384289256734, 8067.394945379003, 8080.353571714366, 8255.25702686737, 8270.545120018456, 9440.197583205505, 10387.68836934893, 10802.027817496462, 10806.945965270123, 10814.0], "value": 10860.6115969302, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.2651954684695, 1489.441459452705, 1513.319006447099, 1653.4130383713782, 1817.645559115972, 7845.215478452152, 8284.295834854038, 8287.544185069819, 8305.460145090572, 8326.292265157468, 8086.838263234732, 8418.663253469855, 8636.050034496217, 8670.86993695095, 8741.650333986632, 2352.7294361148474, 2378.797802186816, 2385.451346190377, 2385.654080158376, 2389.7490763469846, 2333.0900948630047, 2342.9414264643433, 2373.0, 2378.452955820154, 2382.1662418153683, 2342.0982921575146, 2370.466645004869, 2370.8696021718883, 2384.833061685683, 2385.272491889973, 2320.9419764505856, 2336.5910965581043, 2356.4815740537056, 2358.811295096388, 2371.1819422299277, 2293.1629955066282, 2314.201600447843, 2345.1791872844537, 2350.7531709170635, 2353.411647088228, 8623.439476434012, 8720.738377848675, 8738.956305218497, 8755.824883502359, 8802.119788021211, 5158.2520534522655, 5194.766235519411, 5327.760250788724, 5330.626856120073, 5331.333583302087, 3617.927641447183, 3703.962960370389, 3704.0369503928905, 3705.0366904605, 3706.425504046877, 1101.8512500812449, 1111.744298811278, 1137.6416428824894, 1140.076538004211, 1148.0356500539503, 11525.596604118899, 11629.302241865431, 11632.360220187886, 11634.0, 11634.534618615246, 4262.381954616625, 4558.521355257651, 4613.192691279013, 4616.930746038812, 4623.791929363187, 9112.271018318519, 9113.3165012623, 9121.817563648823, 9130.497822619687, 9157.404768690014, 5231.764570594332, 5235.267062611238, 5237.004969055867, 5238.476152384807, 5247.422783493813, 3648.069742215718, 4364.75993820343, 4443.2224360736745, 4908.361912951294, 4949.356583644175, 3684.078980254936, 3751.1372384351753, 3820.8280627371278, 3843.6348546888003, 3860.517435320585, 996.0139461932635, 1157.9015783658315, 1162.7093226693328, 1165.0621249893748, 1166.1312322319925, 830.5216714248647, 849.3163003781891, 886.6674996876171, 924.3102571516854, 948.6300342866288, 5688.374278829326, 5818.243628328375, 5876.353601103875, 5974.850628734316, 5997.564793877214, 371.59310554942294, 442.763121729873, 451.0, 454.56816024775935, 457.5744557561433, 3912.726109172331, 4011.638952494231, 4028.6978476613785, 4129.380592911088, 4177.310743727312, 10653.147748180127, 10698.465076746132, 10826.18803589734, 10832.458377081115, 10870.456477176109, 10255.487225638688, 10457.42484163363, 10710.982456666605, 10720.517576708911, 10802.837957430645, 8024.839503209903, 8068.3948703847445, 8269.917300826915, 8284.171582841787, 8296.253337199681, 5907.113932909926, 5936.22829032223, 6100.786472473442, 6156.26124865019, 6208.1308616792785, 8082.7171049013, 9780.657676981271, 9859.30984831055, 10029.14752246038, 10110.69667909964, 9821.754456138653, 10163.593456261668, 10179.389236645826, 10261.84607230907, 10274.897251027693, 6330.461910737639, 6973.12835895513, 7513.8497230054, 7561.054868141482, 7589.354904833151, 8213.0, 8290.253877151084, 8295.170482951657, 8297.668093276201, 8301.211384918422, 7448.8882666758955, 7515.398768098648, 7524.736634217776, 7661.578613176329, 7662.501937374052, 7336.523125996793, 7393.9260607393235, 7404.96297518509, 7413.406927445899, 7445.329920307198, 6881.65591720402, 7048.295170482911, 7060.046893669494, 7065.0, 7077.079979602619, 4889.975550122226, 4989.176785830297, 5007.824726134568, 5024.598032157492, 5138.280640710378, 4935.235038569028, 4941.802327906844, 5133.0, 5139.563137133386, 5213.426523082382], "value": 4451.3573946126735, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.1753384358407, 303.1632693765208, 311.3943380125656, 314.818979087025, 316.1290644275022, 5160.561352285051, 5338.225957236198, 5362.705051222186, 5402.865867982145, 5405.243265942766, 4200.180964711883, 4522.4346956630425, 4663.930041049387, 5081.713229046934, 5249.425172448269, 3464.6881780639737, 4083.836646534135, 4104.281750693632, 4110.671146308288, 4113.9177216455655, 3390.7287417006582, 3957.7229593928437, 4110.6094920982605, 4118.773467459288, 4123.608257215575, 3121.797083189597, 3989.4015897615445, 4071.1654110907343, 4086.693497987648, 4090.0, 3720.6279372062577, 4026.9597304027025, 4067.97966010171, 4070.5725898780497, 4082.8571000015004, 3556.199855032623, 3583.122135076897, 3592.1738000259825, 3593.1495279930596, 3593.2274560969518, 3857.594952529972, 4792.9281060784115, 5209.244659524347, 5221.545873439477, 5231.659942103751, 4554.772261386917, 4561.5175068103, 4575.757878639476, 4579.335996280554, 4579.778807278467, 4992.101421744084, 5218.382301486551, 5572.359178694462, 5582.581306401996, 5595.824876775882, 3163.7186939289622, 3490.2670439207795, 3798.898319487373, 3803.8478460861534, 3841.519810023747, 4889.046635906036, 5128.3076784633595, 5561.277033985596, 5883.352831188566, 5893.263342082239, 3700.2784457031157, 3857.363535016691, 3896.2597106549665, 3905.062784931596, 3906.531216254011, 3653.2328211075974, 3924.0, 3935.4490371348033, 3956.7032472564674, 3957.248122856648, 4506.977465112654, 4538.160440318552, 4551.431071116111, 4569.0, 4574.7941342639, 5132.8460146195675, 5306.575473962073, 5479.21925374255, 5591.099026330986, 5648.378678345379, 1156.6472225970992, 1271.510468469646, 1286.343964578072, 1298.5260379961246, 1306.8627794081674, 863.8488264553678, 871.5947084605689, 908.2143945487238, 915.455304094063, 915.9038300978434, 4252.405347994502, 5163.044836705221, 5185.144451165591, 5188.948110518922, 5225.0, 4736.5973892219545, 4984.277279794482, 5397.3523177218985, 5466.840597963782, 5577.414371491016, 4052.756834589934, 4479.484859241164, 5055.039542486916, 5107.284980102789, 5118.564921981599, 2281.9771802281766, 2511.67348244727, 2523.2682522068762, 2546.22340186245, 2558.577834657298, 4421.7789110544345, 4487.528809475023, 4829.319727551173, 4917.680565618193, 4936.963237720047, 3999.7000224983244, 4132.586741325843, 4276.679249056333, 4283.87148385549, 4844.539768721966, 4435.802333369972, 4622.907541849144, 4803.95196048042, 4825.538975122713, 4915.926261106015, 3833.5399752029934, 3971.8411263549133, 3990.521137463523, 4368.9126217476105, 4593.793279302507, 3803.801802432225, 4129.442525259052, 4141.0, 4718.905621887475, 4736.644751643709, 4872.366592342973, 4887.772077153166, 4906.362172917497, 4910.0, 5009.574186194214, 2799.5380762174013, 3224.935501290008, 3296.9340613187123, 3315.867365305457, 3362.815045172441, 4767.356406885026, 4880.92678609814, 5111.412187598474, 5221.791128354823, 5464.890702186013, 4811.615070794258, 5339.145736682112, 5642.943570564406, 5687.402822703557, 5703.971480142573, 5161.922571161361, 5628.086450606814, 5665.348484924287, 5680.318361796611, 5681.9431805681415, 5212.0, 5248.900439823949, 5492.533134683436, 5513.862153446196, 5543.25166102571, 5195.870103247449, 5440.455954404528, 5658.858528536654, 5665.66006039639, 5671.8865622688145, 4935.6051515877925, 5332.440093790251, 5352.571794256372, 5480.205370221375, 5508.862278443071], "value": 3865.6363562280235, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.4376795375861, 739.9075115610549, 753.1827966656174, 797.9880301795474, 798.7763426240647, 15074.553006862974, 16432.260548275328, 16440.917795411035, 16461.0, 16463.50609481716, 14716.337764800583, 14865.331060102295, 14965.25173741314, 15419.92290038551, 16290.185490725446, 862.090494528274, 872.8428882801094, 887.2148148888248, 888.7778055486128, 896.274018045382, 877.1623099939575, 880.3661363818072, 880.8766772651834, 880.9956649419667, 882.0826340605762, 730.5835673666013, 932.752820502564, 937.2689302344148, 937.8593211018334, 938.0994245524271, 756.8788993761, 888.1917455115878, 926.7729406295433, 927.2859897878595, 931.1107891963159, 670.5540815357781, 671.8421171024805, 673.636236432326, 674.8447856992871, 675.7060678604822, 12304.138710290283, 15227.923860380737, 15265.923670381688, 15274.847251527564, 15289.923550382176, 13089.214647121204, 14556.636084097876, 14573.927130364385, 14852.925735371255, 14874.851251487455, 11759.647210583697, 12042.09684273683, 12354.505819767199, 12361.69095772604, 12627.242365458102, 3888.9027774305587, 4105.651019663303, 4108.034611866209, 4151.27352713274, 4157.812898419578, 118056.40971795085, 122364.0, 123043.38478307641, 123316.9999999991, 123379.3831030839, 11365.943170284096, 11496.712582185512, 11538.192326537062, 11996.64010079699, 12219.144659873902, 23944.40138996539, 23960.64059039116, 23988.88005559961, 23990.760092399203, 24010.639840402408, 8026.357891368793, 8044.517328960281, 8069.354451643854, 8207.507549547046, 8222.342212622976, 4251.489821221411, 4275.940990621179, 4335.393044973706, 4338.587834155814, 4461.08547747711, 3693.4644476550748, 3791.981040094837, 3808.6953043756434, 3810.714196435223, 3817.1602247505502, 655.5837043477426, 668.2348710726192, 681.7920534237105, 683.1426559667585, 683.8871586188322, 568.2499101186477, 639.1850390751829, 645.8191706322237, 693.5249354192293, 696.9337912898267, 8843.665848544682, 9410.670626528039, 9624.66313679018, 9725.951370243103, 9873.75315617101, 502.43977964569973, 593.2673148661399, 593.7120496559165, 614.8893199224183, 665.218368417108, 4461.308497182942, 4552.408186935677, 4781.545753153445, 5087.135187018216, 5216.765245563959, 153081.23459382632, 160472.0, 161304.0, 164920.3507964929, 170603.14698426428, 136366.0, 138910.30544847212, 139609.60390395965, 140043.0, 144999.0, 91636.0, 111906.32140517898, 114939.42530287128, 124622.0, 127576.36211818882, 9764.388408764391, 9877.30858839903, 10036.799264014533, 10215.233857460571, 10285.74285642865, 54566.45433545614, 56001.43998560125, 58057.0, 59040.704796475744, 59854.40145598488, 44761.0, 72494.38808924987, 76557.85163222445, 86856.69714954155, 94778.0, 12984.415701293276, 13062.608121756362, 13392.0, 13760.793588096387, 13774.110161065977, 13632.386542605409, 14435.2060636666, 15031.398744050115, 15163.696726065638, 15508.612284692974, 13843.515476958259, 14091.929540352232, 14245.28773561297, 14304.427822886968, 14321.785173222619, 11214.775704486028, 13845.723085538435, 14053.859461405254, 14427.567172984827, 14569.9801013928, 7693.538387696756, 13594.048416610742, 13705.588832335046, 14207.573772786833, 14271.357788899317, 10749.785004299714, 11037.668869933916, 11131.387773672526, 11282.153838462313, 11980.28118312904, 11154.372198428482, 11303.378314192798, 11330.94334528322, 11617.361045142261, 11707.88292117102], "value": 7989.808371670903, "unit": "score"}], "name": "dromaeo_css", "value": 7479.70576670158, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 113.0, 114.0, 108.0, 114.0, 113.0, 113.0, 113.0, 114.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 89.0, 197.0, 139.0, 135.0, 129.0, 107.0, 153.0, 113.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 183.0, 182.0, 185.0, 184.0, 179.0, 182.0, 190.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 57.0, 61.0, 86.0, 58.0, 57.0, 63.0, 61.0, 82.0, 57.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 102.0, 71.0, 70.0, 69.0, 95.0, 69.0, 68.0, 102.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 86.0, 88.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 120.0, 150.0, 94.0, 93.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 83.0, 86.0, 85.0, 85.0, 86.0, 86.0, 82.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 53.0, 54.0, 51.0, 53.0, 54.0, 53.0, 53.0, 55.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 89.0, 108.0, 111.0, 76.0, 69.0, 73.0, 69.0, 70.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 85.0, 86.0, 84.0, 86.0, 84.0, 87.0, 90.0, 87.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 133.0, 137.0, 136.0, 143.0, 153.0, 144.0, 228.0, 137.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 161.0, 168.0, 165.0, 166.0, 166.0, 165.0, 170.0, 165.0, 184.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 71.0, 65.0, 87.0, 65.0, 67.0, 68.0, 66.0, 68.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1450.1999999999998, "alertThreshold": 2.0}]} 20:29:07 INFO - Return code: 0 20:29:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:29:07 INFO - # TBPL SUCCESS # 20:29:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:29:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:29:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:29:07 INFO - 2016-08-12 20:29:07.610 screenresolution[1664:10033] starting screenresolution argv=screenresolution get 20:29:07 INFO - 2016-08-12 20:29:07.612 screenresolution[1664:10033] Display 0: 1600x1200x32@60 20:29:07 INFO - 2016-08-12 20:29:07.619 screenresolution[1665:10036] starting screenresolution argv=screenresolution list 20:29:07 INFO - Available Modes on Display 0 20:29:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:29:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:29:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:29:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:29:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:29:07 INFO - Intel Iris: 20:29:07 INFO - Chipset Model: Intel Iris 20:29:07 INFO - Type: GPU 20:29:07 INFO - Bus: Built-In 20:29:07 INFO - VRAM (Dynamic, Max): 1536 MB 20:29:07 INFO - Vendor: Intel (0x8086) 20:29:07 INFO - Device ID: 0x0a2e 20:29:07 INFO - Revision ID: 0x0009 20:29:07 INFO - Displays: 20:29:07 INFO - Display: 20:29:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:29:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:29:07 INFO - Display Serial Number: 1600x1200 60 20:29:07 INFO - Main Display: Yes 20:29:07 INFO - Mirror: Off 20:29:07 INFO - Online: Yes 20:29:07 INFO - Rotation: Supported 20:29:07 INFO - Return code: 0 20:29:07 INFO - Running post-action listener: _resource_record_post_action 20:29:07 INFO - [mozharness: 2016-08-13 03:29:07.857768Z] Finished run-tests step (success) 20:29:07 INFO - Running post-run listener: _resource_record_post_run 20:29:07 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37176320; Write bytes: 702221312; Read time: 240; Write time: 1997 20:29:07 INFO - TinderboxPrint: CPU usage
53.6% 20:29:07 INFO - TinderboxPrint: I/O read bytes / time
37,176,320 / 240 20:29:07 INFO - TinderboxPrint: I/O write bytes / time
702,221,312 / 1,997 20:29:07 INFO - TinderboxPrint: CPU idle
2,248.2 (46.4%) 20:29:07 INFO - TinderboxPrint: CPU system
970.2 (20.0%) 20:29:07 INFO - TinderboxPrint: CPU user
1,631.4 (33.6%) 20:29:07 INFO - TinderboxPrint: Swap in / out
441,839,616 / 0 20:29:07 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 385810944; Write bytes: 380753408; Read time: 29127; Write time: 827 20:29:07 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36205568; Write bytes: 293922304; Read time: 226; Write time: 1101 20:29:08 INFO - Running post-run listener: _upload_blobber_files 20:29:08 INFO - Blob upload gear active. 20:29:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:29:08 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 20:29:08 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'] 20:29:08 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 20:29:08 INFO - (blobuploader) - INFO - Open directory for files ... 20:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:29:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:09 INFO - (blobuploader) - INFO - Done attempting. 20:29:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:29:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:29:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:10 INFO - (blobuploader) - INFO - Done attempting. 20:29:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:29:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:29:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:29:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:29:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:29:13 INFO - (blobuploader) - INFO - Done attempting. 20:29:13 INFO - (blobuploader) - INFO - Iteration through files over. 20:29:13 INFO - Return code: 0 20:29:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:29:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:29:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/99c80bb7eb5e95d298d1245db7bb6bc94907bbeba248566b8b831b6edd384733bfe9f2e10e54f8dc855e20dbf3f2f4f155878c001a2f4d87f8f7aa152f83540d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3bb5b617a7a4dfe5b43c6633dd68a5a08061785d61fcc5be780bef9a6aec3236d0023b3c81e750ed6143d8b4d6c93288a2c8baa005f3743c1eb7d25e1510c26b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/29ca92b6d5056074e5343dd5d4e6d8c0fb864450f7cb23fc4b90f263bdb91dcc79bcd5d6f1590319a680e75a6b97bedcc53550eec02c410bc0d7c3c2a1d4ba1b"} 20:29:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:29:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:29:13 INFO - Contents: 20:29:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/99c80bb7eb5e95d298d1245db7bb6bc94907bbeba248566b8b831b6edd384733bfe9f2e10e54f8dc855e20dbf3f2f4f155878c001a2f4d87f8f7aa152f83540d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3bb5b617a7a4dfe5b43c6633dd68a5a08061785d61fcc5be780bef9a6aec3236d0023b3c81e750ed6143d8b4d6c93288a2c8baa005f3743c1eb7d25e1510c26b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/29ca92b6d5056074e5343dd5d4e6d8c0fb864450f7cb23fc4b90f263bdb91dcc79bcd5d6f1590319a680e75a6b97bedcc53550eec02c410bc0d7c3c2a1d4ba1b"} 20:29:13 INFO - Running post-run listener: copy_logs_to_upload_dir 20:29:13 INFO - Copying logs to upload dir... 20:29:13 INFO - mkdir: /builds/slave/test/build/upload/logs 20:29:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.778109 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-12 20:29:13.140709) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 20:29:13.144041) ========= 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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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/99c80bb7eb5e95d298d1245db7bb6bc94907bbeba248566b8b831b6edd384733bfe9f2e10e54f8dc855e20dbf3f2f4f155878c001a2f4d87f8f7aa152f83540d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3bb5b617a7a4dfe5b43c6633dd68a5a08061785d61fcc5be780bef9a6aec3236d0023b3c81e750ed6143d8b4d6c93288a2c8baa005f3743c1eb7d25e1510c26b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/29ca92b6d5056074e5343dd5d4e6d8c0fb864450f7cb23fc4b90f263bdb91dcc79bcd5d6f1590319a680e75a6b97bedcc53550eec02c410bc0d7c3c2a1d4ba1b"} build_url:https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010636 build_url: 'https://queue.taskcluster.net/v1/task/TpOWf3YEScy3UDmhDOMn1A/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/99c80bb7eb5e95d298d1245db7bb6bc94907bbeba248566b8b831b6edd384733bfe9f2e10e54f8dc855e20dbf3f2f4f155878c001a2f4d87f8f7aa152f83540d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3bb5b617a7a4dfe5b43c6633dd68a5a08061785d61fcc5be780bef9a6aec3236d0023b3c81e750ed6143d8b4d6c93288a2c8baa005f3743c1eb7d25e1510c26b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/29ca92b6d5056074e5343dd5d4e6d8c0fb864450f7cb23fc4b90f263bdb91dcc79bcd5d6f1590319a680e75a6b97bedcc53550eec02c410bc0d7c3c2a1d4ba1b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-12 20:29:13.177002) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 20:29:13.177326) ========= 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.G5l5HUfQK7/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.b1RwRVUcbs/Listeners TMPDIR=/var/folders/7f/fg9p30710059mjlmd28p1g2w00000w/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.005038 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-12 20:29:13.225531) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 20:29:13.225866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-12 20:29:13.226274) ========= ========= Total master_lag: 0.18 =========