builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0010 starttime: 1471524067.57 results: success (0) buildid: 20160817023213 builduid: 5758dd056a7343f18d5a9e9aaddd1b0a revision: cc88aaa9ae2032fc7dbb62ad3ce40db2659cdca5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:07.566884) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:07.567320) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:07.567611) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:07.596301) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:07.596708) ========= 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.WkeLB2AgGG/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.Ttb02XApNB/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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-18 05:41:07-- 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% 8.08M=0.001s 2016-08-18 05:41:07 (8.08 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084895 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:07.704431) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:07.704763) ========= 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.WkeLB2AgGG/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.Ttb02XApNB/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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.020932 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:07.753348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:07.753726) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev cc88aaa9ae2032fc7dbb62ad3ce40db2659cdca5 --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 cc88aaa9ae2032fc7dbb62ad3ce40db2659cdca5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WkeLB2AgGG/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.Ttb02XApNB/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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-18 05:41:07,832 truncating revision to first 12 chars 2016-08-18 05:41:07,832 Setting DEBUG logging. 2016-08-18 05:41:07,832 attempt 1/10 2016-08-18 05:41:07,832 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/cc88aaa9ae20?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 05:41:08,493 unpacking tar archive at: autoland-cc88aaa9ae20/testing/mozharness/ program finished with exit code 0 elapsedTime=0.923782 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:08.700321) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:08.700654) ========= script_repo_revision: cc88aaa9ae2032fc7dbb62ad3ce40db2659cdca5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:08.701072) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:08.701352) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:41:08.719927) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-18 05:41:08.720229) ========= /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.WkeLB2AgGG/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.Ttb02XApNB/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:41:08 INFO - MultiFileLogger online at 20160818 05:41:08 in /builds/slave/test 05:41:08 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 05:41:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:41:08 INFO - {'append_to_log': False, 05:41:08 INFO - 'base_work_dir': '/builds/slave/test', 05:41:08 INFO - 'blob_upload_branch': 'Autoland', 05:41:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:41:08 INFO - 'branch': 'Autoland', 05:41:08 INFO - 'buildbot_json_path': 'buildprops.json', 05:41:08 INFO - 'config_files': ('talos/mac_config.py',), 05:41:08 INFO - 'default_actions': ('clobber', 05:41:08 INFO - 'read-buildbot-config', 05:41:08 INFO - 'download-and-extract', 05:41:08 INFO - 'populate-webroot', 05:41:08 INFO - 'create-virtualenv', 05:41:08 INFO - 'install', 05:41:08 INFO - 'run-tests'), 05:41:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:41:08 INFO - 'download_minidump_stackwalk': True, 05:41:08 INFO - 'download_symbols': 'ondemand', 05:41:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:41:08 INFO - 'tooltool.py': '/tools/tooltool.py', 05:41:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:41:08 INFO - '/tools/misc-python/virtualenv.py')}, 05:41:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:41:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:41:08 INFO - 'installer_path': 'installer.exe', 05:41:08 INFO - 'log_level': 'info', 05:41:08 INFO - 'log_name': 'talos', 05:41:08 INFO - 'log_to_console': True, 05:41:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:41:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:41:08 INFO - 'opt_config_files': (), 05:41:08 INFO - 'pip_index': False, 05:41:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:41:08 INFO - 'cmd': ('bash', 05:41:08 INFO - '-c', 05:41:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:41:08 INFO - 'enabled': True, 05:41:08 INFO - 'halt_on_failure': False, 05:41:08 INFO - 'name': 'check_screen_resolution'},), 05:41:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:41:08 INFO - 'cmd': ('bash', 05:41:08 INFO - '-c', 05:41:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:41:08 INFO - 'enabled': True, 05:41:08 INFO - 'halt_on_failure': False, 05:41:08 INFO - 'name': 'check_screen_resolution'},), 05:41:08 INFO - 'run_cmd_checks_enabled': True, 05:41:08 INFO - 'sps_profile': False, 05:41:08 INFO - 'sps_profile_interval': 0, 05:41:08 INFO - 'suite': 'dromaeojs', 05:41:08 INFO - 'system_bits': '32', 05:41:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:41:08 INFO - 'title': 't-yosemite-r7-0010', 05:41:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:41:08 INFO - 'use_talos_json': True, 05:41:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:41:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:41:08 INFO - 'work_dir': 'build'} 05:41:08 INFO - [mozharness: 2016-08-18 12:41:08.929830Z] Running clobber step. 05:41:08 INFO - Running pre-action listener: _resource_record_pre_action 05:41:08 INFO - Running main action method: clobber 05:41:08 INFO - rmtree: /builds/slave/test/build 05:41:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:41:10 INFO - Running post-action listener: _resource_record_post_action 05:41:10 INFO - [mozharness: 2016-08-18 12:41:10.401326Z] Finished clobber step (success) 05:41:10 INFO - [mozharness: 2016-08-18 12:41:10.401526Z] Running read-buildbot-config step. 05:41:10 INFO - Running pre-action listener: _resource_record_pre_action 05:41:10 INFO - Running main action method: read_buildbot_config 05:41:10 INFO - Using buildbot properties: 05:41:10 INFO - { 05:41:10 INFO - "project": "", 05:41:10 INFO - "product": "firefox", 05:41:10 INFO - "script_repo_revision": "production", 05:41:10 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 05:41:10 INFO - "repository": "", 05:41:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 05:41:10 INFO - "buildid": "20160817023213", 05:41:10 INFO - "pgo_build": "False", 05:41:10 INFO - "basedir": "/builds/slave/test", 05:41:10 INFO - "buildnumber": 158, 05:41:10 INFO - "slavename": "t-yosemite-r7-0010", 05:41:10 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:41:10 INFO - "platform": "yosemite_r7", 05:41:10 INFO - "scheduler": "tests-autoland-macosx64-talos", 05:41:10 INFO - "branch": "autoland", 05:41:10 INFO - "repo_path": "integration/autoland", 05:41:10 INFO - "revision": "cc88aaa9ae2032fc7dbb62ad3ce40db2659cdca5", 05:41:10 INFO - "stage_platform": "macosx64", 05:41:10 INFO - "builduid": "5758dd056a7343f18d5a9e9aaddd1b0a", 05:41:10 INFO - "slavebuilddir": "test" 05:41:10 INFO - } 05:41:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:41:10 INFO - Running post-action listener: _resource_record_post_action 05:41:10 INFO - [mozharness: 2016-08-18 12:41:10.404981Z] Finished read-buildbot-config step (success) 05:41:10 INFO - [mozharness: 2016-08-18 12:41:10.405091Z] Running download-and-extract step. 05:41:10 INFO - Running pre-action listener: _resource_record_pre_action 05:41:10 INFO - Running main action method: download_and_extract 05:41:10 INFO - mkdir: /builds/slave/test/build/tests 05:41:10 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')]} 05:41:10 INFO - trying https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:41:10 INFO - Downloading https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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 05:41:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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 05:41:12 INFO - Downloaded 1199 bytes. 05:41:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:41:12 INFO - Using the following test package requirements: 05:41:12 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:41:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:41:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:41:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'jsshell-mac.zip'], 05:41:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:41:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:41:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:41:12 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:41:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:41:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:41:12 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:41:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:41:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:12 INFO - trying https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:41:12 INFO - Downloading https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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 05:41:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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 05:41:13 INFO - Downloaded 16337526 bytes. 05:41:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:41:20 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:41: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')]} 05:41:20 INFO - trying https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:41:20 INFO - Downloading https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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 05:41:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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 05:41:22 INFO - Downloaded 16337526 bytes. 05:41:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:41: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')]} 05:41:28 INFO - trying https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:41:28 INFO - Downloading https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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 05:41:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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 05:41:29 INFO - Downloaded 14413719 bytes. 05:41:29 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:30 INFO - trying https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:41:30 INFO - Downloading https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:41:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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 05:41:33 INFO - Downloaded 111296405 bytes. 05:41:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:41:33 INFO - mkdir: /builds/slave/test/properties 05:41:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:41:33 INFO - Writing to file /builds/slave/test/properties/build_url 05:41:33 INFO - Contents: 05:41:33 INFO - build_url:https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:41:34 INFO - Running post-action listener: _resource_record_post_action 05:41:34 INFO - Running post-action listener: set_extra_try_arguments 05:41:34 INFO - [mozharness: 2016-08-18 12:41:34.355704Z] Finished download-and-extract step (success) 05:41:34 INFO - [mozharness: 2016-08-18 12:41:34.355838Z] Running populate-webroot step. 05:41:34 INFO - Running pre-action listener: _resource_record_pre_action 05:41:34 INFO - Running main action method: populate_webroot 05:41:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:41:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:41:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:41:34 INFO - '--tppagecycles', 05:41:34 INFO - '7'], 05:41:34 INFO - 'tests': ['tsvgm']}}, 05:41:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:41:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:41:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:41:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:41:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:34 INFO - 'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:34 INFO - 'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['damp', 'tps']}, 05:41:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:34 INFO - 'tests': ['damp', 'tps']}, 05:41:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['dromaeo_dom']}, 05:41:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:41:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['basic_compositor_video']}, 05:41:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:41:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['a11yr', 05:41:34 INFO - 'ts_paint', 05:41:34 INFO - 'tpaint', 05:41:34 INFO - 'sessionrestore', 05:41:34 INFO - 'sessionrestore_no_auto_restore', 05:41:34 INFO - 'tabpaint']}, 05:41:34 INFO - 'other-e10s': {'tests': ['a11yr', 05:41:34 INFO - 'ts_paint', 05:41:34 INFO - 'tpaint', 05:41:34 INFO - 'sessionrestore', 05:41:34 INFO - 'sessionrestore_no_auto_restore', 05:41:34 INFO - 'tabpaint']}, 05:41:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:41:34 INFO - 'ts_paint', 05:41:34 INFO - 'tpaint', 05:41:34 INFO - 'sessionrestore', 05:41:34 INFO - 'sessionrestore_no_auto_restore', 05:41:34 INFO - 'tabpaint']}, 05:41:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:41:34 INFO - 'ts_paint', 05:41:34 INFO - 'tpaint', 05:41:34 INFO - 'sessionrestore', 05:41:34 INFO - 'sessionrestore_no_auto_restore', 05:41:34 INFO - 'tabpaint']}, 05:41:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['a11yr', 05:41:34 INFO - 'ts_paint', 05:41:34 INFO - 'tpaint', 05:41:34 INFO - 'sessionrestore', 05:41:34 INFO - 'sessionrestore_no_auto_restore', 05:41:34 INFO - 'tabpaint']}, 05:41:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['a11yr', 05:41:34 INFO - 'ts_paint', 05:41:34 INFO - 'tpaint', 05:41:34 INFO - 'sessionrestore', 05:41:34 INFO - 'sessionrestore_no_auto_restore', 05:41:34 INFO - 'tabpaint']}, 05:41:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['tsvgx', 05:41:34 INFO - 'tsvgr_opacity', 05:41:34 INFO - 'tart', 05:41:34 INFO - 'tscrollx', 05:41:34 INFO - 'cart']}, 05:41:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:41:34 INFO - 'tsvgr_opacity', 05:41:34 INFO - 'tart', 05:41:34 INFO - 'tscrollx', 05:41:34 INFO - 'cart']}, 05:41:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:34 INFO - 'talos_options': ['--disable-e10s'], 05:41:34 INFO - 'tests': ['tp5o']}, 05:41:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:34 INFO - 'tests': ['tp5o']}, 05:41:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:34 INFO - 'talos_options': ['--disable-e10s', 05:41:34 INFO - '--xperf_path', 05:41:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:34 INFO - 'tests': ['tp5n']}, 05:41:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:34 INFO - 'talos_options': ['--xperf_path', 05:41:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:34 INFO - 'tests': ['tp5n']}}, 05:41:34 INFO - 'talos.zip': {'path': '', 05:41:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:41:34 INFO - Running post-action listener: _resource_record_post_action 05:41:34 INFO - [mozharness: 2016-08-18 12:41:34.374465Z] Finished populate-webroot step (success) 05:41:34 INFO - [mozharness: 2016-08-18 12:41:34.374594Z] Running create-virtualenv step. 05:41:34 INFO - Running pre-action listener: _resource_record_pre_action 05:41:34 INFO - Running main action method: create_virtualenv 05:41:34 INFO - Creating virtualenv /builds/slave/test/build/venv 05:41:34 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 05:41:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:41:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:41:34 INFO - Using real prefix '/tools/python27' 05:41:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:41:35 INFO - Installing distribute.............................................................................................................................................................................................done. 05:41:38 INFO - Installing pip.................done. 05:41:38 INFO - Return code: 0 05:41:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:41:38 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')]} 05:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:38 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')]} 05:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:38 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 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:41:38 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 05:41:38 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 05:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:38 INFO - 'HOME': '/Users/cltbld', 05:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:38 INFO - 'LOGNAME': 'cltbld', 05:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:38 INFO - 'MOZ_NO_REMOTE': '1', 05:41:38 INFO - 'NO_EM_RESTART': '1', 05:41:38 INFO - 'PAGER': '/bin/cat', 05:41:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:38 INFO - 'PWD': '/builds/slave/test', 05:41:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:38 INFO - 'SHELL': '/bin/bash', 05:41:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:41:38 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:41:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:38 INFO - 'USER': 'cltbld', 05:41:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:38 INFO - 'XPC_FLAGS': '0x0', 05:41:38 INFO - 'XPC_SERVICE_NAME': '0', 05:41:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:38 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 05:41:38 INFO - Cleaning up... 05:41:38 INFO - Return code: 0 05:41:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:41:38 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')]} 05:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:38 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')]} 05:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:38 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 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:41:38 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 05:41:38 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 05:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:38 INFO - 'HOME': '/Users/cltbld', 05:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:38 INFO - 'LOGNAME': 'cltbld', 05:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:38 INFO - 'MOZ_NO_REMOTE': '1', 05:41:38 INFO - 'NO_EM_RESTART': '1', 05:41:38 INFO - 'PAGER': '/bin/cat', 05:41:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:38 INFO - 'PWD': '/builds/slave/test', 05:41:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:38 INFO - 'SHELL': '/bin/bash', 05:41:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:41:38 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:41:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:38 INFO - 'USER': 'cltbld', 05:41:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:38 INFO - 'XPC_FLAGS': '0x0', 05:41:38 INFO - 'XPC_SERVICE_NAME': '0', 05:41:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:38 INFO - Downloading/unpacking psutil>=3.1.1 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:41:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:41 INFO - Installing collected packages: psutil 05:41:41 INFO - Running setup.py install for psutil 05:41:41 INFO - building 'psutil._psutil_osx' extension 05:41:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:41:42 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 05:41:42 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 05:41:42 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 05:41:42 INFO - building 'psutil._psutil_posix' extension 05:41:42 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 05:41:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:41:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:41:42 INFO - ^ 05:41:42 INFO - 1 warning generated. 05:41:42 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 05:41:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:42 INFO - Successfully installed psutil 05:41:42 INFO - Cleaning up... 05:41:42 INFO - Return code: 0 05:41:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:42 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 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:41:42 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 05:41:42 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 05:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:42 INFO - 'HOME': '/Users/cltbld', 05:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:42 INFO - 'LOGNAME': 'cltbld', 05:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:42 INFO - 'MOZ_NO_REMOTE': '1', 05:41:42 INFO - 'NO_EM_RESTART': '1', 05:41:42 INFO - 'PAGER': '/bin/cat', 05:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:42 INFO - 'PWD': '/builds/slave/test', 05:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:42 INFO - 'SHELL': '/bin/bash', 05:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:41:42 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:42 INFO - 'USER': 'cltbld', 05:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:42 INFO - 'XPC_FLAGS': '0x0', 05:41:42 INFO - 'XPC_SERVICE_NAME': '0', 05:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:41:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:41:45 INFO - Installing collected packages: mozsystemmonitor 05:41:45 INFO - Running setup.py install for mozsystemmonitor 05:41:45 INFO - Successfully installed mozsystemmonitor 05:41:45 INFO - Cleaning up... 05:41:45 INFO - Return code: 0 05:41:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:41:45 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')]} 05:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:45 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')]} 05:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:45 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 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:41:45 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 05:41:45 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 05:41:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:41:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:45 INFO - 'HOME': '/Users/cltbld', 05:41:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:45 INFO - 'LOGNAME': 'cltbld', 05:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:45 INFO - 'MOZ_NO_REMOTE': '1', 05:41:45 INFO - 'NO_EM_RESTART': '1', 05:41:45 INFO - 'PAGER': '/bin/cat', 05:41:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:45 INFO - 'PWD': '/builds/slave/test', 05:41:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:45 INFO - 'SHELL': '/bin/bash', 05:41:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:41:45 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:41:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:45 INFO - 'USER': 'cltbld', 05:41:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:45 INFO - 'XPC_FLAGS': '0x0', 05:41:45 INFO - 'XPC_SERVICE_NAME': '0', 05:41:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:45 INFO - Downloading/unpacking jsonschema==2.5.1 05:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:41:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:41:48 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 05:41:48 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 05:41:48 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 05:41:48 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 05:41:48 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 05:41:48 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 05:41:48 INFO - Downloading functools32-3.2.3-2.tar.gz 05:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:41:48 INFO - warning: no files found matching '*.txt' 05:41:48 INFO - no previously-included directories found matching 'build' 05:41:48 INFO - no previously-included directories found matching 'dist' 05:41:48 INFO - no previously-included directories found matching '.git*' 05:41:48 INFO - Installing collected packages: jsonschema, functools32 05:41:48 INFO - Running setup.py install for functools32 05:41:48 INFO - warning: no files found matching '*.txt' 05:41:48 INFO - no previously-included directories found matching 'build' 05:41:48 INFO - no previously-included directories found matching 'dist' 05:41:48 INFO - no previously-included directories found matching '.git*' 05:41:49 INFO - Successfully installed jsonschema functools32 05:41:49 INFO - Cleaning up... 05:41:49 INFO - Return code: 0 05:41:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:41:49 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')]} 05:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:49 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')]} 05:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:49 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 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:41:49 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 05:41:49 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 05:41:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:41:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:49 INFO - 'HOME': '/Users/cltbld', 05:41:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:49 INFO - 'LOGNAME': 'cltbld', 05:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:49 INFO - 'MOZ_NO_REMOTE': '1', 05:41:49 INFO - 'NO_EM_RESTART': '1', 05:41:49 INFO - 'PAGER': '/bin/cat', 05:41:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:49 INFO - 'PWD': '/builds/slave/test', 05:41:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:49 INFO - 'SHELL': '/bin/bash', 05:41:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:41:49 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:41:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:49 INFO - 'USER': 'cltbld', 05:41:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:49 INFO - 'XPC_FLAGS': '0x0', 05:41:49 INFO - 'XPC_SERVICE_NAME': '0', 05:41:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:41:49 INFO - Cleaning up... 05:41:49 INFO - Return code: 0 05:41:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:41:49 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')]} 05:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:49 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')]} 05:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:49 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 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:41:49 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 05:41:49 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 05:41:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:41:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:49 INFO - 'HOME': '/Users/cltbld', 05:41:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:49 INFO - 'LOGNAME': 'cltbld', 05:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:49 INFO - 'MOZ_NO_REMOTE': '1', 05:41:49 INFO - 'NO_EM_RESTART': '1', 05:41:49 INFO - 'PAGER': '/bin/cat', 05:41:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:49 INFO - 'PWD': '/builds/slave/test', 05:41:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:49 INFO - 'SHELL': '/bin/bash', 05:41:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:41:49 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:41:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:49 INFO - 'USER': 'cltbld', 05:41:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:49 INFO - 'XPC_FLAGS': '0x0', 05:41:49 INFO - 'XPC_SERVICE_NAME': '0', 05:41:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:49 INFO - Downloading/unpacking blobuploader==1.2.4 05:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:52 INFO - Downloading blobuploader-1.2.4.tar.gz 05:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:41:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:41:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:53 INFO - Downloading docopt-0.6.1.tar.gz 05:41:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:41:53 INFO - Installing collected packages: blobuploader, requests, docopt 05:41:53 INFO - Running setup.py install for blobuploader 05:41:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:41:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:41:53 INFO - Running setup.py install for requests 05:41:53 INFO - Running setup.py install for docopt 05:41:54 INFO - Successfully installed blobuploader requests docopt 05:41:54 INFO - Cleaning up... 05:41:54 INFO - Return code: 0 05:41:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41: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')]} 05:41:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:54 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 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:41:54 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 05:41:54 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 05:41:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:41:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:54 INFO - 'HOME': '/Users/cltbld', 05:41:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:54 INFO - 'LOGNAME': 'cltbld', 05:41:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:54 INFO - 'MOZ_NO_REMOTE': '1', 05:41:54 INFO - 'NO_EM_RESTART': '1', 05:41:54 INFO - 'PAGER': '/bin/cat', 05:41:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:54 INFO - 'PWD': '/builds/slave/test', 05:41:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:54 INFO - 'SHELL': '/bin/bash', 05:41:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:41:54 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:41:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:54 INFO - 'USER': 'cltbld', 05:41:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:54 INFO - 'XPC_FLAGS': '0x0', 05:41:54 INFO - 'XPC_SERVICE_NAME': '0', 05:41:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-lxYita-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Azjqs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Z2kch8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-zznE1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-6q16kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-FV4MgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Y3tWGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-6uab72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-JkMYQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-7CQzJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-7vg8xA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-l3eO0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-D64Ugx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-cHWY17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Nbo36n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:41:56 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-HpuDvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:41:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:41:56 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-VVOPMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:41:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:41:56 INFO - Running setup.py install for manifestparser 05:41:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:41:56 INFO - Running setup.py install for mozcrash 05:41:56 INFO - Running setup.py install for mozdebug 05:41:56 INFO - Running setup.py install for mozdevice 05:41:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:41:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:41:56 INFO - Running setup.py install for mozfile 05:41:56 INFO - Running setup.py install for mozhttpd 05:41:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Running setup.py install for mozinfo 05:41:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Running setup.py install for mozInstall 05:41:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Running setup.py install for mozleak 05:41:57 INFO - Running setup.py install for mozlog 05:41:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Running setup.py install for moznetwork 05:41:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:41:57 INFO - Running setup.py install for mozprocess 05:41:58 INFO - Running setup.py install for mozprofile 05:41:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Running setup.py install for mozrunner 05:41:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Running setup.py install for mozscreenshot 05:41:58 INFO - Running setup.py install for moztest 05:41:58 INFO - Running setup.py install for mozversion 05:41:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:41:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:41:58 INFO - Cleaning up... 05:41:58 INFO - Return code: 0 05:41:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:41:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:41:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:41:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:58 INFO - 'HOME': '/Users/cltbld', 05:41:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:58 INFO - 'LOGNAME': 'cltbld', 05:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:58 INFO - 'MOZ_NO_REMOTE': '1', 05:41:58 INFO - 'NO_EM_RESTART': '1', 05:41:58 INFO - 'PAGER': '/bin/cat', 05:41:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:58 INFO - 'PWD': '/builds/slave/test', 05:41:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:58 INFO - 'SHELL': '/bin/bash', 05:41:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:41:58 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:41:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:58 INFO - 'USER': 'cltbld', 05:41:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:58 INFO - 'XPC_FLAGS': '0x0', 05:41:58 INFO - 'XPC_SERVICE_NAME': '0', 05:41:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-nEGZUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:59 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)) 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-_E3FYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:59 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)) 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-MGvcXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:59 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)) 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-ecP_Jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:59 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)) 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-80jKp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-0ISQPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-JeQt9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-bf5Xvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Jlv1KY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:42:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-240qat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:42:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-CBwRZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:42:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-yKdPwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:42:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-NORKBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:42:00 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)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:42:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Cav0qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-hRlx3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:42:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-2TAoHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:42:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-cl0icJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:42:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:42:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:03 INFO - Downloading blessings-1.6.tar.gz 05:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:42:03 INFO - Installing collected packages: blessings 05:42:03 INFO - Running setup.py install for blessings 05:42:03 INFO - Successfully installed blessings 05:42:03 INFO - Cleaning up... 05:42:03 INFO - Return code: 0 05:42:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:42:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:42:04 INFO - Reading from file tmpfile_stdout 05:42:04 INFO - Current package versions: 05:42:04 INFO - blessings == 1.6 05:42:04 INFO - blobuploader == 1.2.4 05:42:04 INFO - docopt == 0.6.1 05:42:04 INFO - functools32 == 3.2.3-2 05:42:04 INFO - jsonschema == 2.5.1 05:42:04 INFO - manifestparser == 1.1 05:42:04 INFO - mozInstall == 1.12 05:42:04 INFO - mozcrash == 0.17 05:42:04 INFO - mozdebug == 0.1 05:42:04 INFO - mozdevice == 0.48 05:42:04 INFO - mozfile == 1.2 05:42:04 INFO - mozhttpd == 0.7 05:42:04 INFO - mozinfo == 0.9 05:42:04 INFO - mozleak == 0.1 05:42:04 INFO - mozlog == 3.3 05:42:04 INFO - moznetwork == 0.27 05:42:04 INFO - mozprocess == 0.23 05:42:04 INFO - mozprofile == 0.28 05:42:04 INFO - mozrunner == 6.12 05:42:04 INFO - mozscreenshot == 0.1 05:42:04 INFO - mozsystemmonitor == 0.3 05:42:04 INFO - moztest == 0.7 05:42:04 INFO - mozversion == 1.4 05:42:04 INFO - psutil == 3.1.1 05:42:04 INFO - requests == 1.2.3 05:42:04 INFO - wsgiref == 0.1.2 05:42:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:04 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 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:42:04 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 05:42:04 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 05:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:04 INFO - 'HOME': '/Users/cltbld', 05:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:04 INFO - 'LOGNAME': 'cltbld', 05:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:04 INFO - 'MOZ_NO_REMOTE': '1', 05:42:04 INFO - 'NO_EM_RESTART': '1', 05:42:04 INFO - 'PAGER': '/bin/cat', 05:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:04 INFO - 'PWD': '/builds/slave/test', 05:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:04 INFO - 'SHELL': '/bin/bash', 05:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:42:04 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:04 INFO - 'USER': 'cltbld', 05:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:04 INFO - 'XPC_FLAGS': '0x0', 05:42:04 INFO - 'XPC_SERVICE_NAME': '0', 05:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 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)) 05:42:05 INFO - Cleaning up... 05:42:05 INFO - Return code: 0 05:42:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:42:05 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')]} 05:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:05 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')]} 05:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:05 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 0x100a47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100adc618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe443469240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, '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.WkeLB2AgGG/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.Ttb02XApNB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 05:42:05 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 05:42:05 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 05:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:05 INFO - 'HOME': '/Users/cltbld', 05:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:05 INFO - 'LOGNAME': 'cltbld', 05:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:05 INFO - 'MOZ_NO_REMOTE': '1', 05:42:05 INFO - 'NO_EM_RESTART': '1', 05:42:05 INFO - 'PAGER': '/bin/cat', 05:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:05 INFO - 'PWD': '/builds/slave/test', 05:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:05 INFO - 'SHELL': '/bin/bash', 05:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:42:05 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:05 INFO - 'USER': 'cltbld', 05:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:05 INFO - 'XPC_FLAGS': '0x0', 05:42:05 INFO - 'XPC_SERVICE_NAME': '0', 05:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:42:05 INFO - Cleaning up... 05:42:05 INFO - Return code: 0 05:42:05 INFO - Running post-action listener: _resource_record_post_action 05:42:05 INFO - Running post-action listener: _start_resource_monitoring 05:42:05 INFO - Starting resource monitoring. 05:42:05 INFO - [mozharness: 2016-08-18 12:42:05.448108Z] Finished create-virtualenv step (success) 05:42:05 INFO - [mozharness: 2016-08-18 12:42:05.449082Z] Running install step. 05:42:05 INFO - Running pre-action listener: _resource_record_pre_action 05:42:05 INFO - Running main action method: install 05:42:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:42:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:42:05 INFO - Reading from file tmpfile_stdout 05:42:05 INFO - Detecting whether we're running mozinstall >=1.0... 05:42:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:42:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:42:05 INFO - Reading from file tmpfile_stdout 05:42:05 INFO - Output received: 05:42:05 INFO - Usage: mozinstall [options] installer 05:42:05 INFO - Options: 05:42:05 INFO - -h, --help show this help message and exit 05:42:05 INFO - -d DEST, --destination=DEST 05:42:05 INFO - Directory to install application into. [default: 05:42:05 INFO - "/builds/slave/test"] 05:42:05 INFO - --app=APP Application being installed. [default: firefox] 05:42:05 INFO - mkdir: /builds/slave/test/build/application 05:42:05 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'] 05:42:05 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 05:42:39 INFO - Reading from file tmpfile_stdout 05:42:39 INFO - Output received: 05:42:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:42:39 INFO - Running post-action listener: _resource_record_post_action 05:42:39 INFO - [mozharness: 2016-08-18 12:42:39.784622Z] Finished install step (success) 05:42:39 INFO - [mozharness: 2016-08-18 12:42:39.784786Z] Running run-tests step. 05:42:39 INFO - Running pre-action listener: _resource_record_pre_action 05:42:39 INFO - Running main action method: run_tests 05:42:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:42:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:42:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:42:39 INFO - 2016-08-18 05:42:39.799 screenresolution[1917:16259] starting screenresolution argv=screenresolution get 05:42:39 INFO - 2016-08-18 05:42:39.801 screenresolution[1917:16259] Display 0: 1600x1200x32@60 05:42:39 INFO - 2016-08-18 05:42:39.808 screenresolution[1918:16261] starting screenresolution argv=screenresolution list 05:42:39 INFO - Available Modes on Display 0 05:42:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:42:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:42:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:42:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:42:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:42:40 INFO - Intel Iris: 05:42:40 INFO - Chipset Model: Intel Iris 05:42:40 INFO - Type: GPU 05:42:40 INFO - Bus: Built-In 05:42:40 INFO - VRAM (Dynamic, Max): 1536 MB 05:42:40 INFO - Vendor: Intel (0x8086) 05:42:40 INFO - Device ID: 0x0a2e 05:42:40 INFO - Revision ID: 0x0009 05:42:40 INFO - Displays: 05:42:40 INFO - Display: 05:42:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:42:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:42:40 INFO - Display Serial Number: 1600x1200 60 05:42:40 INFO - Main Display: Yes 05:42:40 INFO - Mirror: Off 05:42:40 INFO - Online: Yes 05:42:40 INFO - Rotation: Supported 05:42:40 INFO - Return code: 0 05:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:42:40 INFO - Python 2.7.3 05:42:40 INFO - Return code: 0 05:42:40 INFO - grabbing minidump binary from tooltool 05:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:40 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009abd30>, '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 0x100710db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009a3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:42:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:42:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:42:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:42:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:42:40 INFO - Return code: 0 05:42:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:42:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:42:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:42:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:42:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:42:40 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/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0010', '--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 05:42:40 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/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0010 --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 05:42:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WkeLB2AgGG/Render', 05:42:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:40 INFO - 'HOME': '/Users/cltbld', 05:42:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:40 INFO - 'LOGNAME': 'cltbld', 05:42:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:42:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:40 INFO - 'MOZ_NO_REMOTE': '1', 05:42:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:42:40 INFO - 'NO_EM_RESTART': '1', 05:42:40 INFO - 'PAGER': '/bin/cat', 05:42:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:40 INFO - 'PWD': '/builds/slave/test', 05:42:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:42:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:40 INFO - 'SHELL': '/bin/bash', 05:42:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ttb02XApNB/Listeners', 05:42:40 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 05:42:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:40 INFO - 'USER': 'cltbld', 05:42:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:40 INFO - 'XPC_FLAGS': '0x0', 05:42:40 INFO - 'XPC_SERVICE_NAME': '0', 05:42:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:40 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/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0010', '--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 05:42:40 INFO - mozversion application_buildid: 20160817023213 05:42:40 INFO - mozversion application_changeset: cc88aaa9ae2032fc7dbb62ad3ce40db2659cdca5 05:42:40 INFO - mozversion application_display_name: Nightly 05:42:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:42:40 INFO - mozversion application_name: Firefox 05:42:40 INFO - mozversion application_remotingname: firefox 05:42:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:42:40 INFO - mozversion application_vendor: Mozilla 05:42:40 INFO - mozversion application_version: 51.0a1 05:42:40 INFO - mozversion platform_buildid: 20160817023213 05:42:40 INFO - mozversion platform_changeset: cc88aaa9ae2032fc7dbb62ad3ce40db2659cdca5 05:42:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:42:40 INFO - mozversion platform_version: 51.0a1 05:42:40 INFO - using testdate: 1471524160 05:42:40 INFO - actual date: 1471524160 05:42:40 INFO - starting webserver on 'localhost:53507' 05:42:40 INFO - SUITE-START | Running 2 tests 05:42:40 INFO - TEST-START | dromaeo_css 05:42:40 INFO - operating with platform_type : mac_ 05:42:40 INFO - Initialising browser for dromaeo_css test... 05:42:40 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpbkvIGo/profile http://localhost:53507/getInfo.html) 05:42:42 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 05:42:42 INFO - PROCESS | 1928 | colorDepth:24 05:42:42 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 05:42:42 INFO - PROCESS | 1928 | __metrics 05:42:42 INFO - PROCESS | 1928 | 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" 05:42:42 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:42:43 INFO - TEST-INFO | 1928: exit 0 05:42:43 INFO - Browser initialized. 05:42:43 INFO - Running cycle 1/1 for dromaeo_css test... 05:42:43 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpbkvIGo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:55 INFO - PROCESS | 1930 | RSS: Main: 228335616 05:42:55 INFO - PROCESS | 1930 | 05:43:47 INFO - PROCESS | 1930 | 1471524227094 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] 05:45:59 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53507/tests/dromaeo/cssquery-ext.html) 05:46:00 INFO - PROCESS | 1930 | RSS: Main: 351150080 05:46:00 INFO - PROCESS | 1930 | 05:49:03 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-ext.html (next: http://localhost:53507/tests/dromaeo/cssquery-jquery.html) 05:49:04 INFO - PROCESS | 1930 | RSS: Main: 372174848 05:49:04 INFO - PROCESS | 1930 | 05:52:06 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53507/tests/dromaeo/cssquery-mootools.html) 05:52:07 INFO - PROCESS | 1930 | RSS: Main: 364818432 05:52:07 INFO - PROCESS | 1930 | 05:55:09 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53507/tests/dromaeo/cssquery-prototype.html) 05:55:10 INFO - PROCESS | 1930 | RSS: Main: 351842304 05:55:10 INFO - PROCESS | 1930 | 05:58:13 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53507/tests/dromaeo/cssquery-yui.html) 05:58:14 INFO - PROCESS | 1930 | RSS: Main: 328605696 05:58:14 INFO - PROCESS | 1930 | 06:01:17 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53507/tests/dromaeo/cssquery-yui.html (next: http://localhost:53507/tests/dromaeo/cssquery-dojo.html) 06:01:18 INFO - PROCESS | 1930 | RSS: Main: 434163712 06:01:18 INFO - PROCESS | 1930 | 06:01:18 INFO - PROCESS | 1930 | __start_tp_report 06:01:18 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 06:01:18 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 06:01:18 INFO - PROCESS | 1930 | |i|pagename|runs| 06:01:18 INFO - PROCESS | 1930 | |0;dojo.html;3341.5655964724583;3370.3090866372395;3376.4935259711056;3388.915277118071;3429.8628054877804;5600.1599760036015;5720.685362305072;5724.885502289952;5750.453706897849;5769.711514424278;5722.570807189457;5765.769369225226;5779.046457334545;5787.334456537499;5791;4426;5203.271541984125;5239.004589128053;5256.5794736421185;5267.762950667229;5298.761555730001;5308.256844041837;5312.654677445973;5313.442088580701;5314.282571852808;5223.738813059332;5261.105612045962;5263.447338029509;5271.841844744664;5302.257683924254;5310.946890531103;5335.493128152819;5341.866453338659;5352.785888564492;5388.676679399248;4254.532001479834;5141.485851414828;5149.794008239666;5260.210968354739;5265.97313523863;5665.490105890449;5671.404502527216;5719.6854173020465;5742.7415766290615;5752.654840709529;6272.372762723737;6279.8744025119695;6285.340039295854;6290.276618188876;6301.08634248036;6148.415900489446;6210.534209934273;6285.214348206474;6293.1504246927;6347.206599175103;5802.187693722883;5807.88384232313;5810.912836307459;5813.302403711582;5860.501857342174;6323.494120470351;6332.1135041094285;6349.142865713162;6360.6501642409185;6362.0774987627465;5965.701714914237;6055.212822333068;6055.909161362583;6064.05665512994;6167.599106058092;6148.678034222664;6197.845053873689;6227.19046523949;6231.594946328474;6254.093156492283;6183.443490085914;6252.874942501125;6261.812145635638;6267.1539342189135;6289.339619339995;6229.719662615103;6301.054841773771;6301.495880329563;6302.369763023661;6317.020861766434;4865.245886887538;4932.939418025142;4958.528939750718;4961.900761984813;5005.199168133154;4396.846110386121;4429.623482004065;4443.400140980991;4455.977720111378;4477.462704475483;5707.200991861143;5729.799457018984;5732.512736417451;5733.483986441157;5809.564282678816;6142.314611233224;6226.470749986302;6274.780382686584;6334.936650633527;6339.334369891095;5716.599838011382;5725.08398656213;5730.283714535683;5769.682667453247;5777.480026797609;5402.891942161214;5430.022595932691;5434.239206511092;5444;5469.535089517356;5238;5641.12562552805;6128.142060111677;6319.052142178618;6334.8733025338315;6102.2771839767565;6207.441330280297;6286.15136956505;6290.49676025926;6309.526785491014;6360.300366959538;6401.8399540010005;6411.96794016027;6412.647304398303;6413.583117097373;6215.81352559424;6244.500439964701;6254.343293954069;6277.748890044347;6278.309385967631;6117.279373342116;6181.381861813783;6230.034344676583;6266.279377871603;6322.083297921868;5116.872078198075;5966.820995370146;6115.84710382244;6120.846978825565;6123.785667501616;6085.087236914498;6089.665068421279;6107;6133.693315334126;6139.3553676863285;4964.453910069961;5792.594518383841;5801.912971305518;5853.678047707417;5893.705314734332;5974.641521508724;6015.939840601713;6027;6036.456718895321;6059.151718759465;5754.51086657622;5841.678707671119;5872.588918775645;5873.765049397976;5897.67562784051 06:01:18 INFO - PROCESS | 1930 | |1;ext.html;467.51378566291015;470.87521806721213;474.51599368643963;522.6289334572454;523.8501489231137;30879.382412351766;32678.509822352684;32695.50956735651;32792.18019549509;40821.795891020505;40675.18649627006;43197.56802431975;43206.35190472138;43214;43246.78376608113;9847.901520984788;10006.449645269508;10009.549570269355;10026.248031397638;10040.196784257241;8864;9039.502827344493;9848.753781155458;9966.551505182284;11958.342291173982;11961.803819618055;12022.819657705142;12295.508179672846;12315.014798816073;12326.568570100048;10662.946685266563;12255.632331030083;12262.386880655931;12267.999999999955;12273;12016.339101349422;12095;12096.395180240952;12108.576199832964;12115.515379384815;40670.59329406727;41000.179996400206;41015.97460063492;41061.589384106075;41155.588444115776;3586.5337506124038;3646.8541258349633;3885.708571857122;4089.0799570096788;4227.471566054243;31843.52234716481;32014;32848.83575582107;33070;33160.668393316;3269.1990462336644;3361.8151001694896;3382.120648631349;3384.915377115567;3391.101358140095;457953;459085;460203;460689;469290;59876.40123598708;59996.700016499635;60215;60380.39619603835;60470.09294860581;62882;63756;63833.36166638367;63842.36157638364;63865;16570.337186512403;16785.580360491087;16815.32738690463;16817.32730690783;16821.74767378466;12985;13004.739905201843;13066.281354525592;13076.869231307564;13083.345832708326;14925.955183137075;15262.771058434133;16962.83037169637;17242.568935776457;17305.56736081608;5343.332083489564;5421.267135976287;6066.300496279202;6315.526335524854;6322.683865806691;705.7776800307887;869.8303830753069;872.8865247517782;998.2962011781676;1004.1414590525142;30208.813713968284;30531.847340763597;30554;30858.919937802068;31499;1045.2213101239624;1054.7415883108688;1071.8499410082595;1129.76274982253;1213.6662417835166;12957.870421295855;15816.52550423489;15879.603009924613;16099.597510062342;17008.404705835237;644472;645480;647920;648423;649996;58881.82236355249;59156;59240.81518369608;59640.40359596435;59827;1235.9567415140427;1270.0284282524062;1271.364317841098;1375.69734658375;1393.8188035555313;23731.881340593878;31160.68839311578;32159.839200803846;32654;32776.83611581927;49938;57354.3968404726;57365;57383.42616573781;57394.42605574058;606058.9091163548;613544;614426;615092.1699306581;615324;26456.074037408598;27028.72971270262;27053.323666908484;40622;41013.38479922863;37477;39761.60238397695;40754.79622601868;41472;48800.75599622121;38602.6139738599;39649.40525892172;39777.00557486086;41194;41221;36199;39223.43106275717;39527;40315;40837.795811020755;38486.80756596199;39256.411153832145;39299;39619.405708914965;39635;32819;34426;35134.64865351314;35271.64728352786;35478.645213547534;25788.35529111787;25941.740582594688;25971;26342.773074693752;26745.732542674323 06:01:18 INFO - PROCESS | 1930 | |2;jquery.html;374.3131353965471;379.08072923161336;388.91638297765985;398.99002524936856;410.9486314210724;19523.219071237134;20999.055042523087;21561;21641.56716865664;21953.67069493959;20516.38450846477;20714.067866945985;20857.061432235547;21504.784952150472;21792.564148717054;5096.439391666913;5814.680192589406;6105.32841387447;6144.907826382608;6178.443940045395;3847.3844184930417;4059.269331520325;4060.3706425504092;6125.081237814319;6176.351483094278;3809.4095415210686;4189.601987811168;6746.898796518056;6764.120664313632;6775.2208496023095;6117.96941015292;6315.305316415237;6737.629430381327;6756.797296081126;6761.661916904135;5969.880602387928;5979.850503737398;5983.880322393527;5987;5989.670568118751;13362.799558006638;13443;13470.865291347158;13637.249951252677;13713.725725485434;2192.8136108430804;2228.6211344071385;2363.751806060356;2370.7392186859433;2418.3107814273026;9536.331142050114;9800.607975680965;9888.950555247178;9910.454924979123;10430.843537346946;1832.3403574713093;1877.1458986161265;1927.1616846671743;1950.444123424817;2018.8082132197417;66989.99999999952;103646;108798.45600772025;117883.4105829474;122358.38820805839;10615.52230149645;11421.486033128529;11652.067834573214;11653.53385864573;11754.588589399502;11672.649820505398;14510.347034383478;14521.564353069425;14532.854671453362;14550.927245363706;7669.808254793563;7909;8162.83674326522;8333.041700204432;8354.331653467709;7104.2895710429575;8552.230299273095;8628.654853805778;8788.692395766118;8790.956045219733;3199.232184275757;3904.8047597620007;3932.7050471214775;3978.3634618460906;3995.5604883462797;1892.706630472279;1941.8155275249112;1947.3087054095874;1947.7857435682063;1947.951301217481;726.5604309392847;821.6302663801314;915.5595362491861;969.7866469376726;993.1062044160313;19758.11088501021;21184.682229766568;22023.008964596633;22056.33830985073;22077.23382129426;767.2672597669213;948.9762755931018;973.8928717841031;976.6142373762324;977.6480467031863;10155.949220253851;11501.287383640458;11570.826437603442;11578.305301681927;11595.420228988516;572980.1350993302;579749;581921;590582;595930;122400;132582.7079510617;132668;133267;136704;813.8413009463097;912.3750231091724;993.2749093162032;993.9105480506789;1039.8284283093355;17237.74143387825;20360.59278814386;20458.488537786674;20492;20552.58894822125;56838;57351;58778.412215879005;59327.11009334777;59510.70244648749;69656;82137.58931205305;83085;83987.5800620993;84567.5771621138;8029.237222463857;8196.795080123045;8364.205400486944;8406;8413.980644645364;11191.216614837209;11546.364949927834;13139.60581182566;13566.050376473551;13625.727485450434;9140.771480713036;11236.381998990135;11309.151813613847;11471.885281147415;11503.367314797431;9758.80482390334;11167.441627918734;11376;11508.59719909799;11690.47392867306;10413.895861041596;10653.840192397276;11345.886541134812;11518.308901465938;11814.409279536161;6866.450683945172;8081.272685458337;8267.214614611603;8363.163683631588;8593.484390936565;8451.704190353308;10788.406637635006;11536.769704482758;11730.472128754056;11747.647570572897 06:01:18 INFO - PROCESS | 1930 | |3;mootools.html;1341.24219815804;1489.4265707702534;1535.470262759348;1665.3088968078248;1828.378351360538;7423.925760742391;7692.115406728228;8354.415190936637;8711.520866352348;8732.738017859458;8562.74311770648;8642.91357086429;8645.351598630097;8648.524331161783;8652.567371631425;2376.8098552115835;2402.519496100787;2403.5072810073893;2405.675233843426;2406.9759302407015;2348.4833336665993;2386.3795413192606;2391.390195500145;2391.7488663690324;2400.4238982644215;2349.5066036132434;2360.2565191964563;2380.785729284364;2390;2391.689080419543;2326.6626339180893;2358.5518751437257;2368.029108065685;2368.988155059231;2370.3718514593647;2324.198151637699;2349.8485741986474;2350.471143992605;2357.351728274738;2357.669926210332;8624.482531048157;8697.739067827975;8709.825803483958;8800;8817.735467935972;5274.841754747364;5467.890642187134;5476.7535460904355;5478.863028424282;5487.067198576252;3604.333198358312;3617.439296908983;3679.6136405677285;3684.778913265213;3691.0403295143187;1112.7830073135742;1113.3041848844473;1113.4544073403974;1116.9106471482262;1122.186414849236;11383.544658213661;11426.457428247164;11486.827697584544;11503.712407189803;11513.884861151366;4363.367311739843;4455.977720111378;4611.769411529477;4625.32932724753;4625.930611040837;8989.730308090637;8997;8999.235065019415;9008.684696035738;9017.684381046633;5253.947460525423;5254.238135470336;5254.97372513135;5258.6581872178185;5259.184826351921;4270.359446083113;4415.955840441618;4572.542745725467;4885.340479035285;4935.3090567320605;3645;3708.79601621908;3789.1853251551047;3794.2601192767697;3795.1081495848453;1157.8552680914886;1175.3946717440524;1182.373342128656;1189.7501524679742;1192.5766352944754;748.8464864702723;851.7998270406449;884.2660591708994;906.7325139083986;935.8409070458039;5587.413321601254;5616.83751755577;5744.224529688522;5883.646981181143;6120.326764055951;857.6055014693311;889.3818795936762;894.6868595991351;931.84624536952;1001.5042553935848;3374.6625337466057;3944.6252606002386;3958.762474251554;4048.8987775305854;4086.9386959195635;10443.060124588823;10744.355338679705;10755.78488430227;10757;10780.245382823126;10658.200634952409;10735.141188704885;10751.892481075245;10757.46212689378;10780.94609526963;8154.2253485918745;8289.958550207331;8292.37807164465;8304.086550479564;8313.833723325499;4790.688605240648;5753.482186603226;5846.269216347957;6062.696865156637;6168.074788781719;9700.6119755209;9873.062059104373;9965.950170249102;9998.350107243006;10019.749506262402;9475.384100033476;10066;10097.040781125781;10231.897681023393;10268.07587317145;6930.410915072275;7142.25006374344;7453.9254607455405;7555.962220188864;7601.733939312097;8056.154103819249;8204.179582041748;8354.582270886553;8401.15988401155;8417.873731893904;7357.484976051625;7416.0729908761405;7462.589557574385;7502.774916752506;7614.961925190339;6853.314668533107;7044.084269044992;7351.632418379167;7359.668814903235;7414.295641914009;6673.499487538357;6872.725090996304;6962.617056061763;6971.407430368273;7028.437724982092;4942.777575009061;4981.2030075187795;4987.326710893983;5047.318611987482;5105.668131571584;4331.328644060176;4791.520847915181;4957.454679985271;4985.376827896513;5107.770150343169 06:01:18 INFO - PROCESS | 1930 | |4;prototype.html;302.227807950686;302.61870043744887;312.0762542873095;318.79437762643096;319.1096839816911;5285.2072189171595;5313.8937221255555;5335.839924802257;5341.30563026807;5353.223782551532;4878.853634390965;4968.354113965189;5047.0915235257735;5242.161254199329;5294.438140748479;4018.403528841692;4041.737287076345;4062.796860156995;4070.470838790953;4088.4480595119576;3470.8958731238104;3955.782431966241;4070.633642972132;4078.796060196993;4093.9795301023455;3801.885943421702;3964.108075682963;3975.4036894465917;4048.736832105904;4056.269871423143;3488.6511348865165;3692.9261414771554;3811.5235595550557;3978.1844721831953;4011.7994100295014;3006.2033561106323;3031.9382819682605;3109.181129039492;3481.485553784112;3482.2687235680537;4989.950100498985;5156.74216289184;5166.689998600096;5194.441667499735;5211.218317252443;4174.645155161815;4316.40293091555;4498.865034048951;4538.2738761797955;4568.5203053679215;5009.541508057831;5416.808302173516;5562.7774889004395;5579.721013949327;5585.385607583163;3163.557102005721;3787;3794.677452416548;3795.6014618464937;3822.503074600312;4963.553280204763;5330.9733451332495;5588.748506317225;5736.590631934598;5756.856078598069;3827.8660246891227;3831.9616803832164;3836.9616303836606;3840.7695538267794;3860.189360234382;3878.5151856017997;3903.2388684206308;3906.4726261954274;3909.2963266611714;3917.7257591968287;4253.91525161082;4315.8489452869;4517.480489743656;4549.272116461417;4572.5993853226655;5602.467765562265;5609.467100625434;5622.100463925753;5624.606277560613;5633.492985631313;1258.5086672293362;1277.4123903004722;1311.0363882546321;1342.4630147940902;1343.6305016120646;840.5755093677653;861.814709837388;862.6204470033165;863.8208844926755;864.8140649760206;4602.286645569942;4999.475055119233;5104.132297509433;5118.641695081308;5159.948400516022;4969.4782047884455;5225.372955245395;5689.800856969986;5715.342735585377;5717.627769335413;4951.117318435783;5082.695038297714;5087.109755792721;5122.462141475092;5132.794688212504;2298.4598619324447;2366.455715185517;2407.650890620885;2537.61935709645;2537.9111731089324;4946.975265123651;4954.5788607968725;5010.874728131826;5079.257201111433;5081.441041485434;4223.61365828306;4550.590446859798;4885.95114048855;4928.334674818853;4938.913439043405;4430.889227769331;6008.309044459856;6046.5767396281835;6057.878842423127;6069.696515174135;4823.348847905488;4851.690043688114;5787.421257874179;5821.476067153976;5831.7375718091935;5572.412968416102;5718.598943258929;5723.6851973141875;5769.653820770767;5794.275715535558;4760.6191504678845;5815.924054050014;5856.33978763273;5950.523958083257;5996.190514280516;3340.4800815629314;3436.9484457733656;3653.415453527423;3823.6941044716914;3908.3746600543777;4716.321269409866;4791.592714619296;5265.657732247391;5522.972385138049;5868.970655146697;5217.765200565907;5454.972725136349;5546.833594992157;5613.88772224545;5636.435421882951;4437.423134992559;5578.541304391303;5609.579281553818;5620.26936498269;5622.85942851432;4541.455025396974;5316.760745766373;5509.834704958857;5522.244873486068;5529.861753456195;4716.410448693913;4883.536064073907;5628.240187574789;5642.633228840113;5643.887122257614;5247.921281180862;5489.752961116679;5507.421720719267;5522.006038912874;5524.281843360337 06:01:18 INFO - PROCESS | 1930 | |5;yui.html;731.3344856180875;738.3760722189751;746.1046743907312;790.1032328307373;808.1595141053305;14385.921055920808;14807;14823.555293341204;14829.332680029398;14830;14725.484608038721;14938.925305373485;14939.327730252138;14966.625834354147;15015;585.979490717825;698.5703792167806;846.7840700621364;847.8219573889476;878.3149143667949;855.2601999270648;867.7136544940155;872.6422166911567;874.6282829797342;877.4822854515835;916.660835490866;917.5870858113875;918.8529835226365;920.089111779337;921.4287141971987;725.1008749151015;748.3265061444689;752.9623518824092;915.1260546178416;918.0406475233397;553.382977979554;557.4954666027252;660.4287291492824;679.8980152977043;684.9623270720108;15143.621409464742;15188.088714677044;15201.163935983517;15203.467878624195;15266.465673701367;14195.716085678332;14405.495807646786;14439.422423103064;14578.562643120724;14670.046446980912;12910.453334661075;13115;13139.080264381497;13145.474181032747;13188.164366174891;4612.654050946193;4674.27548729944;4717.221658426355;4757.976210118962;4855.562999330042;111799.99999999919;113357.43321283424;113826;113916;114235.42882285535;9644.95177524122;9701;11271;11342.60300889465;11366;22556.887215563816;22637.43406414853;22695.09219631196;22697.88651056767;23261.3021609352;7937.32532734724;7964.362850971908;7996.200379962073;8002.159773223728;8074.596270186467;4260.510041345285;4317.201317756162;4381.474223093249;4488.551144885485;4565.109803588269;3495.702865256482;3645.198056427582;3646.872359467406;3652.086978255436;3658.8536458541894;661.3386613386614;669.6350488983516;674.3020973292619;681.6148875885109;690.1683471416931;597.6384287506083;628.7265039707651;635.269440143829;649.2306616659274;650.3561474140566;9174;9189.402688825205;9210.63157473707;9210.95394523023;9237.538123093818;564.5257983294052;574.2620732358898;597.6115524908757;620.1813606039984;643.6073994863129;4024.8188831502066;4252.29837076885;4416.447944006999;4563.315502674559;4584.575547276853;166623;167110;168677;169612.3038769621;173557;112906;144034;144693.27653361665;145811;147004;119223.40388298003;119734;119734.4013279928;121736.39131804461;129077.99999999812;8568.485890846569;8673.783155420913;8684.305255579664;8819.559022048743;8822.458254965282;48936;66443;68551.31448685649;69185.65407172932;70253.29746702468;80607.59696201481;90849.54575227082;93268.53365733128;96730;98081.50959245158;11099.278546894426;11320.433978301216;12484.563040293546;13210.405531750903;13301.733965320833;12384.31886246229;15197.240137992834;15251.313690884159;15561.533154005398;15717.528474145794;14179.619777151658;14275.571732848031;14360.210188439736;14374.85625143777;14643.68136240651;11917.940410297893;13725.588232353046;13863.584092477242;14424;14529.418823246951;12400.069994750611;13450.730985380042;13554.25451600132;13757.862421376058;14325.212113333866;11109.333439993627;11170.27393219438;11537.388114746156;11850.58522951727;11896.702582435508;10950.178736594946;11401.429928503707;11542.884571154182;11716.355600442057;11762.529498820293 06:01:18 INFO - PROCESS | 1930 | __end_tp_report 06:01:18 INFO - PROCESS | 1930 | __start_cc_report 06:01:18 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1186 06:01:18 INFO - PROCESS | 1930 | __end_cc_report 06:01:18 INFO - PROCESS | 1930 | __startTimestamp1471525278038__endTimestamp 06:01:18 INFO - PROCESS | 1930 | 06:01:18 INFO - PROCESS | 1930 | ------- Summary: start ------- 06:01:18 INFO - PROCESS | 1930 | Number of tests: 6 06:01:18 INFO - PROCESS | 1930 | 06:01:18 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5735.19 Median:5853.68 stddev:649.75 (11.1%) stddev-sans-first:624.02 06:01:18 INFO - PROCESS | 1930 | Values: 3341.6 3370.3 3376.5 3388.9 3429.9 5600.2 5720.7 5724.9 5750.5 5769.7 5722.6 5765.8 5779.0 5787.3 5791.0 4426.0 5203.3 5239.0 5256.6 5267.8 5298.8 5308.3 5312.7 5313.4 5314.3 5223.7 5261.1 5263.4 5271.8 5302.3 5310.9 5335.5 5341.9 5352.8 5388.7 4254.5 5141.5 5149.8 5260.2 5266.0 5665.5 5671.4 5719.7 5742.7 5752.7 6272.4 6279.9 6285.3 6290.3 6301.1 6148.4 6210.5 6285.2 6293.2 6347.2 5802.2 5807.9 5810.9 5813.3 5860.5 6323.5 6332.1 6349.1 6360.7 6362.1 5965.7 6055.2 6055.9 6064.1 6167.6 6148.7 6197.8 6227.2 6231.6 6254.1 6183.4 6252.9 6261.8 6267.2 6289.3 6229.7 6301.1 6301.5 6302.4 6317.0 4865.2 4932.9 4958.5 4961.9 5005.2 4396.8 4429.6 4443.4 4456.0 4477.5 5707.2 5729.8 5732.5 5733.5 5809.6 6142.3 6226.5 6274.8 6334.9 6339.3 5716.6 5725.1 5730.3 5769.7 5777.5 5402.9 5430.0 5434.2 5444.0 5469.5 5238.0 5641.1 6128.1 6319.1 6334.9 6102.3 6207.4 6286.2 6290.5 6309.5 6360.3 6401.8 6412.0 6412.6 6413.6 6215.8 6244.5 6254.3 6277.7 6278.3 6117.3 6181.4 6230.0 6266.3 6322.1 5116.9 5966.8 6115.8 6120.8 6123.8 6085.1 6089.7 6107.0 6133.7 6139.4 4964.5 5792.6 5801.9 5853.7 5893.7 5974.6 6015.9 6027.0 6036.5 6059.2 5754.5 5841.7 5872.6 5873.8 5897.7 06:01:18 INFO - PROCESS | 1930 | 06:01:18 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:71120.44 Median:20561.93 stddev:154854.59 (753.1%) stddev-sans-first:155198.14 06:01:18 INFO - PROCESS | 1930 | Values: 467.5 470.9 474.5 522.6 523.9 30879.4 32678.5 32695.5 32792.2 40821.8 40675.2 43197.6 43206.4 43214.0 43246.8 9847.9 10006.4 10009.5 10026.2 10040.2 8864.0 9039.5 9848.8 9966.6 11958.3 11961.8 12022.8 12295.5 12315.0 12326.6 10662.9 12255.6 12262.4 12268.0 12273.0 12016.3 12095.0 12096.4 12108.6 12115.5 40670.6 41000.2 41016.0 41061.6 41155.6 3586.5 3646.9 3885.7 4089.1 4227.5 31843.5 32014.0 32848.8 33070.0 33160.7 3269.2 3361.8 3382.1 3384.9 3391.1 457953.0 459085.0 460203.0 460689.0 469290.0 59876.4 59996.7 60215.0 60380.4 60470.1 62882.0 63756.0 63833.4 63842.4 63865.0 16570.3 16785.6 16815.3 16817.3 16821.7 12985.0 13004.7 13066.3 13076.9 13083.3 14926.0 15262.8 16962.8 17242.6 17305.6 5343.3 5421.3 6066.3 6315.5 6322.7 705.8 869.8 872.9 998.3 1004.1 30208.8 30531.8 30554.0 30858.9 31499.0 1045.2 1054.7 1071.8 1129.8 1213.7 12957.9 15816.5 15879.6 16099.6 17008.4 644472.0 645480.0 647920.0 648423.0 649996.0 58881.8 59156.0 59240.8 59640.4 59827.0 1236.0 1270.0 1271.4 1375.7 1393.8 23731.9 31160.7 32159.8 32654.0 32776.8 49938.0 57354.4 57365.0 57383.4 57394.4 606058.9 613544.0 614426.0 615092.2 615324.0 26456.1 27028.7 27053.3 40622.0 41013.4 37477.0 39761.6 40754.8 41472.0 48800.8 38602.6 39649.4 39777.0 41194.0 41221.0 36199.0 39223.4 39527.0 40315.0 40837.8 38486.8 39256.4 39299.0 39619.4 39635.0 32819.0 34426.0 35134.6 35271.6 35478.6 25788.4 25941.7 25971.0 26342.8 26745.7 06:01:18 INFO - PROCESS | 1930 | 06:01:18 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:34270.51 Median:2094.25 stddev:97621.29 (4661.4%) stddev-sans-first:97861.97 06:01:18 INFO - PROCESS | 1930 | Values: 374.3 379.1 388.9 399.0 410.9 19523.2 20999.1 21561.0 21641.6 21953.7 20516.4 20714.1 20857.1 21504.8 21792.6 5096.4 5814.7 6105.3 6144.9 6178.4 3847.4 4059.3 4060.4 6125.1 6176.4 3809.4 4189.6 6746.9 6764.1 6775.2 6118.0 6315.3 6737.6 6756.8 6761.7 5969.9 5979.9 5983.9 5987.0 5989.7 13362.8 13443.0 13470.9 13637.2 13713.7 2192.8 2228.6 2363.8 2370.7 2418.3 9536.3 9800.6 9889.0 9910.5 10430.8 1832.3 1877.1 1927.2 1950.4 2018.8 66990.0 103646.0 108798.5 117883.4 122358.4 10615.5 11421.5 11652.1 11653.5 11754.6 11672.6 14510.3 14521.6 14532.9 14550.9 7669.8 7909.0 8162.8 8333.0 8354.3 7104.3 8552.2 8628.7 8788.7 8791.0 3199.2 3904.8 3932.7 3978.4 3995.6 1892.7 1941.8 1947.3 1947.8 1948.0 726.6 821.6 915.6 969.8 993.1 19758.1 21184.7 22023.0 22056.3 22077.2 767.3 949.0 973.9 976.6 977.6 10155.9 11501.3 11570.8 11578.3 11595.4 572980.1 579749.0 581921.0 590582.0 595930.0 122400.0 132582.7 132668.0 133267.0 136704.0 813.8 912.4 993.3 993.9 1039.8 17237.7 20360.6 20458.5 20492.0 20552.6 56838.0 57351.0 58778.4 59327.1 59510.7 69656.0 82137.6 83085.0 83987.6 84567.6 8029.2 8196.8 8364.2 8406.0 8414.0 11191.2 11546.4 13139.6 13566.1 13625.7 9140.8 11236.4 11309.2 11471.9 11503.4 9758.8 11167.4 11376.0 11508.6 11690.5 10413.9 10653.8 11345.9 11518.3 11814.4 6866.5 8081.3 8267.2 8363.2 8593.5 8451.7 10788.4 11536.8 11730.5 11747.6 06:01:18 INFO - PROCESS | 1930 | 06:01:18 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5634.90 Median:4969.33 stddev:3144.88 (63.3%) stddev-sans-first:3137.14 06:01:18 INFO - PROCESS | 1930 | Values: 1341.2 1489.4 1535.5 1665.3 1828.4 7423.9 7692.1 8354.4 8711.5 8732.7 8562.7 8642.9 8645.4 8648.5 8652.6 2376.8 2402.5 2403.5 2405.7 2407.0 2348.5 2386.4 2391.4 2391.7 2400.4 2349.5 2360.3 2380.8 2390.0 2391.7 2326.7 2358.6 2368.0 2369.0 2370.4 2324.2 2349.8 2350.5 2357.4 2357.7 8624.5 8697.7 8709.8 8800.0 8817.7 5274.8 5467.9 5476.8 5478.9 5487.1 3604.3 3617.4 3679.6 3684.8 3691.0 1112.8 1113.3 1113.5 1116.9 1122.2 11383.5 11426.5 11486.8 11503.7 11513.9 4363.4 4456.0 4611.8 4625.3 4625.9 8989.7 8997.0 8999.2 9008.7 9017.7 5253.9 5254.2 5255.0 5258.7 5259.2 4270.4 4416.0 4572.5 4885.3 4935.3 3645.0 3708.8 3789.2 3794.3 3795.1 1157.9 1175.4 1182.4 1189.8 1192.6 748.8 851.8 884.3 906.7 935.8 5587.4 5616.8 5744.2 5883.6 6120.3 857.6 889.4 894.7 931.8 1001.5 3374.7 3944.6 3958.8 4048.9 4086.9 10443.1 10744.4 10755.8 10757.0 10780.2 10658.2 10735.1 10751.9 10757.5 10780.9 8154.2 8290.0 8292.4 8304.1 8313.8 4790.7 5753.5 5846.3 6062.7 6168.1 9700.6 9873.1 9966.0 9998.4 10019.7 9475.4 10066.0 10097.0 10231.9 10268.1 6930.4 7142.3 7453.9 7556.0 7601.7 8056.2 8204.2 8354.6 8401.2 8417.9 7357.5 7416.1 7462.6 7502.8 7615.0 6853.3 7044.1 7351.6 7359.7 7414.3 6673.5 6872.7 6962.6 6971.4 7028.4 4942.8 4981.2 4987.3 5047.3 5105.7 4331.3 4791.5 4957.5 4985.4 5107.8 06:01:18 INFO - PROCESS | 1930 | 06:01:18 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4439.26 Median:4965.95 stddev:1384.77 (27.9%) stddev-sans-first:1353.39 06:01:18 INFO - PROCESS | 1930 | Values: 302.2 302.6 312.1 318.8 319.1 5285.2 5313.9 5335.8 5341.3 5353.2 4878.9 4968.4 5047.1 5242.2 5294.4 4018.4 4041.7 4062.8 4070.5 4088.4 3470.9 3955.8 4070.6 4078.8 4094.0 3801.9 3964.1 3975.4 4048.7 4056.3 3488.7 3692.9 3811.5 3978.2 4011.8 3006.2 3031.9 3109.2 3481.5 3482.3 4990.0 5156.7 5166.7 5194.4 5211.2 4174.6 4316.4 4498.9 4538.3 4568.5 5009.5 5416.8 5562.8 5579.7 5585.4 3163.6 3787.0 3794.7 3795.6 3822.5 4963.6 5331.0 5588.7 5736.6 5756.9 3827.9 3832.0 3837.0 3840.8 3860.2 3878.5 3903.2 3906.5 3909.3 3917.7 4253.9 4315.8 4517.5 4549.3 4572.6 5602.5 5609.5 5622.1 5624.6 5633.5 1258.5 1277.4 1311.0 1342.5 1343.6 840.6 861.8 862.6 863.8 864.8 4602.3 4999.5 5104.1 5118.6 5159.9 4969.5 5225.4 5689.8 5715.3 5717.6 4951.1 5082.7 5087.1 5122.5 5132.8 2298.5 2366.5 2407.7 2537.6 2537.9 4947.0 4954.6 5010.9 5079.3 5081.4 4223.6 4550.6 4886.0 4928.3 4938.9 4430.9 6008.3 6046.6 6057.9 6069.7 4823.3 4851.7 5787.4 5821.5 5831.7 5572.4 5718.6 5723.7 5769.7 5794.3 4760.6 5815.9 5856.3 5950.5 5996.2 3340.5 3436.9 3653.4 3823.7 3908.4 4716.3 4791.6 5265.7 5523.0 5869.0 5217.8 5455.0 5546.8 5613.9 5636.4 4437.4 5578.5 5609.6 5620.3 5622.9 4541.5 5316.8 5509.8 5522.2 5529.9 4716.4 4883.5 5628.2 5642.6 5643.9 5247.9 5489.8 5507.4 5522.0 5524.3 06:01:18 INFO - PROCESS | 1930 | 06:01:18 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:26308.52 Median:3649.48 stddev:43203.94 (1183.8%) stddev-sans-first:43282.46 06:01:18 INFO - PROCESS | 1930 | Values: 731.3 738.4 746.1 790.1 808.2 14385.9 14807.0 14823.6 14829.3 14830.0 14725.5 14938.9 14939.3 14966.6 15015.0 586.0 698.6 846.8 847.8 878.3 855.3 867.7 872.6 874.6 877.5 916.7 917.6 918.9 920.1 921.4 725.1 748.3 753.0 915.1 918.0 553.4 557.5 660.4 679.9 685.0 15143.6 15188.1 15201.2 15203.5 15266.5 14195.7 14405.5 14439.4 14578.6 14670.0 12910.5 13115.0 13139.1 13145.5 13188.2 4612.7 4674.3 4717.2 4758.0 4855.6 111800.0 113357.4 113826.0 113916.0 114235.4 9645.0 9701.0 11271.0 11342.6 11366.0 22556.9 22637.4 22695.1 22697.9 23261.3 7937.3 7964.4 7996.2 8002.2 8074.6 4260.5 4317.2 4381.5 4488.6 4565.1 3495.7 3645.2 3646.9 3652.1 3658.9 661.3 669.6 674.3 681.6 690.2 597.6 628.7 635.3 649.2 650.4 9174.0 9189.4 9210.6 9211.0 9237.5 564.5 574.3 597.6 620.2 643.6 4024.8 4252.3 4416.4 4563.3 4584.6 166623.0 167110.0 168677.0 169612.3 173557.0 112906.0 144034.0 144693.3 145811.0 147004.0 119223.4 119734.0 119734.4 121736.4 129078.0 8568.5 8673.8 8684.3 8819.6 8822.5 48936.0 66443.0 68551.3 69185.7 70253.3 80607.6 90849.5 93268.5 96730.0 98081.5 11099.3 11320.4 12484.6 13210.4 13301.7 12384.3 15197.2 15251.3 15561.5 15717.5 14179.6 14275.6 14360.2 14374.9 14643.7 11917.9 13725.6 13863.6 14424.0 14529.4 12400.1 13450.7 13554.3 13757.9 14325.2 11109.3 11170.3 11537.4 11850.6 11896.7 10950.2 11401.4 11542.9 11716.4 11762.5 06:01:18 INFO - PROCESS | 1930 | -------- Summary: end -------- 06:01:18 INFO - PROCESS | 1930 | 06:01:18 INFO - TEST-INFO | 1930: exit 0 06:01:18 INFO - TEST-OK | dromaeo_css | took 1118342ms 06:01:18 INFO - TEST-START | kraken 06:01:18 INFO - operating with platform_type : mac_ 06:01:18 INFO - Initialising browser for kraken test... 06:01:18 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpEc6_jl/profile http://localhost:53507/getInfo.html) 06:01:20 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 06:01:20 INFO - PROCESS | 1934 | colorDepth:24 06:01:20 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 06:01:20 INFO - PROCESS | 1934 | __metrics 06:01:21 INFO - PROCESS | 1934 | 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" 06:01:21 INFO - PROCESS | 1934 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:01:21 INFO - TEST-INFO | 1934: exit 0 06:01:21 INFO - Browser initialized. 06:01:21 INFO - Running cycle 1/1 for kraken test... 06:01:21 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpEc6_jl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:01:33 INFO - PROCESS | 1935 | RSS: Main: 228540416 06:01:33 INFO - PROCESS | 1935 | 06:01:36 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53507/tests/kraken/driver.html?testName=audio-beat-detection) 06:01:37 INFO - PROCESS | 1935 | RSS: Main: 285298688 06:01:37 INFO - PROCESS | 1935 | 06:01:40 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53507/tests/kraken/driver.html?testName=audio-dft) 06:01:40 INFO - PROCESS | 1935 | RSS: Main: 292593664 06:01:40 INFO - PROCESS | 1935 | 06:01:44 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53507/tests/kraken/driver.html?testName=audio-fft) 06:01:44 INFO - PROCESS | 1935 | RSS: Main: 387346432 06:01:44 INFO - PROCESS | 1935 | 06:01:46 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53507/tests/kraken/driver.html?testName=audio-oscillator) 06:01:47 INFO - PROCESS | 1935 | RSS: Main: 288976896 06:01:47 INFO - PROCESS | 1935 | 06:01:49 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53507/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:01:50 INFO - PROCESS | 1935 | RSS: Main: 293736448 06:01:50 INFO - PROCESS | 1935 | 06:01:52 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53507/tests/kraken/driver.html?testName=imaging-darkroom) 06:01:53 INFO - PROCESS | 1935 | RSS: Main: 330129408 06:01:53 INFO - PROCESS | 1935 | 06:01:56 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53507/tests/kraken/driver.html?testName=imaging-desaturate) 06:01:57 INFO - PROCESS | 1935 | RSS: Main: 331399168 06:01:57 INFO - PROCESS | 1935 | 06:01:59 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53507/tests/kraken/driver.html?testName=json-parse-financial) 06:02:00 INFO - PROCESS | 1935 | RSS: Main: 331046912 06:02:00 INFO - PROCESS | 1935 | 06:02:02 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53507/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:02:02 INFO - PROCESS | 1935 | RSS: Main: 300552192 06:02:02 INFO - PROCESS | 1935 | 06:02:04 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:02:05 INFO - PROCESS | 1935 | RSS: Main: 308342784 06:02:05 INFO - PROCESS | 1935 | 06:02:07 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:02:08 INFO - PROCESS | 1935 | RSS: Main: 304898048 06:02:08 INFO - PROCESS | 1935 | 06:02:11 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:02:11 INFO - PROCESS | 1935 | RSS: Main: 311517184 06:02:11 INFO - PROCESS | 1935 | 06:02:14 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:02:15 INFO - PROCESS | 1935 | RSS: Main: 312918016 06:02:15 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:53507/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53507/tests/kraken/driver.html?testName=ai-astar) 06:02:17 INFO - PROCESS | 1935 | RSS: Main: 356179968 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | __start_tp_report 06:02:17 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 06:02:17 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 06:02:17 INFO - PROCESS | 1935 | |i|pagename|runs| 06:02:17 INFO - PROCESS | 1935 | |0;ai-astar;101;112;111;112;108;113;111;120;151;113 06:02:17 INFO - PROCESS | 1935 | |1;audio-beat-detection;146;117;110;154;130;138;162;116;160;108 06:02:17 INFO - PROCESS | 1935 | |2;audio-dft;186;187;184;183;183;183;183;184;181;183 06:02:17 INFO - PROCESS | 1935 | |3;audio-fft;76;58;61;88;57;55;62;67;84;56 06:02:17 INFO - PROCESS | 1935 | |4;audio-oscillator;94;68;104;70;71;68;95;72;67;103 06:02:17 INFO - PROCESS | 1935 | |5;imaging-gaussian-blur;89;84;87;83;87;83;88;84;86;83 06:02:17 INFO - PROCESS | 1935 | |6;imaging-darkroom;92;130;150;93;93;92;93;94;93;93 06:02:17 INFO - PROCESS | 1935 | |7;imaging-desaturate;84;84;84;85;84;84;84;83;85;86 06:02:17 INFO - PROCESS | 1935 | |8;json-parse-financial;56;54;56;54;54;55;55;53;55;53 06:02:17 INFO - PROCESS | 1935 | |9;json-stringify-tinderbox;65;72;75;76;74;70;72;70;72;72 06:02:17 INFO - PROCESS | 1935 | |10;stanford-crypto-aes;87;85;83;86;98;105;104;105;89;85 06:02:17 INFO - PROCESS | 1935 | |11;stanford-crypto-ccm;142;151;147;153;136;137;138;137;160;144 06:02:17 INFO - PROCESS | 1935 | |12;stanford-crypto-pbkdf2;169;167;169;166;168;167;168;167;166;166 06:02:17 INFO - PROCESS | 1935 | |13;stanford-crypto-sha256-iterative;66;67;68;67;68;66;68;85;86;85 06:02:17 INFO - PROCESS | 1935 | __end_tp_report 06:02:17 INFO - PROCESS | 1935 | __start_cc_report 06:02:17 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,2106 06:02:17 INFO - PROCESS | 1935 | __end_cc_report 06:02:17 INFO - PROCESS | 1935 | __startTimestamp1471525337274__endTimestamp 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | ------- Summary: start ------- 06:02:17 INFO - PROCESS | 1935 | Number of tests: 14 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#0] ai-astar Cycles:10 Average:115.20 Median:112.50 stddev:13.43 (11.9%) stddev-sans-first:13.23 06:02:17 INFO - PROCESS | 1935 | Values: 101.0 112.0 111.0 112.0 108.0 113.0 111.0 120.0 151.0 113.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:142.00 stddev:20.82 (14.7%) stddev-sans-first:21.63 06:02:17 INFO - PROCESS | 1935 | Values: 146.0 117.0 110.0 154.0 130.0 138.0 162.0 116.0 160.0 108.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#2] audio-dft Cycles:10 Average:183.70 Median:183.50 stddev:1.70 (0.9%) stddev-sans-first:1.59 06:02:17 INFO - PROCESS | 1935 | Values: 186.0 187.0 184.0 183.0 183.0 183.0 183.0 184.0 181.0 183.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#3] audio-fft Cycles:10 Average:66.40 Median:64.50 stddev:12.08 (18.7%) stddev-sans-first:12.31 06:02:17 INFO - PROCESS | 1935 | Values: 76.0 58.0 61.0 88.0 57.0 55.0 62.0 67.0 84.0 56.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:70.50 stddev:15.68 (22.2%) stddev-sans-first:15.94 06:02:17 INFO - PROCESS | 1935 | Values: 94.0 68.0 104.0 70.0 71.0 68.0 95.0 72.0 67.0 103.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#5] imaging-gaussian-blur Cycles:10 Average:85.40 Median:86.50 stddev:2.27 (2.6%) stddev-sans-first:2.00 06:02:17 INFO - PROCESS | 1935 | Values: 89.0 84.0 87.0 83.0 87.0 83.0 88.0 84.0 86.0 83.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:93.00 stddev:20.43 (22.0%) stddev-sans-first:21.33 06:02:17 INFO - PROCESS | 1935 | Values: 92.0 130.0 150.0 93.0 93.0 92.0 93.0 94.0 93.0 93.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:84.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 06:02:17 INFO - PROCESS | 1935 | Values: 84.0 84.0 84.0 85.0 84.0 84.0 84.0 83.0 85.0 86.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.08 (2.0%) stddev-sans-first:1.00 06:02:17 INFO - PROCESS | 1935 | Values: 56.0 54.0 56.0 54.0 54.0 55.0 55.0 53.0 55.0 53.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.00 stddev:3.08 (4.3%) stddev-sans-first:2.07 06:02:17 INFO - PROCESS | 1935 | Values: 65.0 72.0 75.0 76.0 74.0 70.0 72.0 70.0 72.0 72.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:85.50 stddev:9.20 (10.8%) stddev-sans-first:9.53 06:02:17 INFO - PROCESS | 1935 | Values: 87.0 85.0 83.0 86.0 98.0 105.0 104.0 105.0 89.0 85.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:145.50 stddev:8.13 (5.6%) stddev-sans-first:8.57 06:02:17 INFO - PROCESS | 1935 | Values: 142.0 151.0 147.0 153.0 136.0 137.0 138.0 137.0 160.0 144.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.50 stddev:1.16 (0.7%) stddev-sans-first:1.05 06:02:17 INFO - PROCESS | 1935 | Values: 169.0 167.0 169.0 166.0 168.0 167.0 168.0 167.0 166.0 166.0 06:02:17 INFO - PROCESS | 1935 | 06:02:17 INFO - PROCESS | 1935 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:68.00 stddev:8.82 (13.0%) stddev-sans-first:9.03 06:02:17 INFO - PROCESS | 1935 | Values: 66.0 67.0 68.0 67.0 68.0 66.0 68.0 85.0 86.0 85.0 06:02:17 INFO - PROCESS | 1935 | -------- Summary: end -------- 06:02:17 INFO - PROCESS | 1935 | 06:02:18 INFO - TEST-INFO | 1935: exit 0 06:02:18 INFO - TEST-OK | kraken | took 59421ms 06:02:18 INFO - SUITE-END | took 1177s 06:02:18 INFO - Completed test suite (00:19:38) 06:02:18 INFO - javascript benchmark 06:02:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3341.5655964724583, 3370.3090866372395, 3376.4935259711056, 3388.915277118071, 3429.8628054877804, 5600.1599760036015, 5720.685362305072, 5724.885502289952, 5750.453706897849, 5769.711514424278, 5722.570807189457, 5765.769369225226, 5779.046457334545, 5787.334456537499, 5791.0, 4426.0, 5203.271541984125, 5239.004589128053, 5256.5794736421185, 5267.762950667229, 5298.761555730001, 5308.256844041837, 5312.654677445973, 5313.442088580701, 5314.282571852808, 5223.738813059332, 5261.105612045962, 5263.447338029509, 5271.841844744664, 5302.257683924254, 5310.946890531103, 5335.493128152819, 5341.866453338659, 5352.785888564492, 5388.676679399248, 4254.532001479834, 5141.485851414828, 5149.794008239666, 5260.210968354739, 5265.97313523863, 5665.490105890449, 5671.404502527216, 5719.6854173020465, 5742.7415766290615, 5752.654840709529, 6272.372762723737, 6279.8744025119695, 6285.340039295854, 6290.276618188876, 6301.08634248036, 6148.415900489446, 6210.534209934273, 6285.214348206474, 6293.1504246927, 6347.206599175103, 5802.187693722883, 5807.88384232313, 5810.912836307459, 5813.302403711582, 5860.501857342174, 6323.494120470351, 6332.1135041094285, 6349.142865713162, 6360.6501642409185, 6362.0774987627465, 5965.701714914237, 6055.212822333068, 6055.909161362583, 6064.05665512994, 6167.599106058092, 6148.678034222664, 6197.845053873689, 6227.19046523949, 6231.594946328474, 6254.093156492283, 6183.443490085914, 6252.874942501125, 6261.812145635638, 6267.1539342189135, 6289.339619339995, 6229.719662615103, 6301.054841773771, 6301.495880329563, 6302.369763023661, 6317.020861766434, 4865.245886887538, 4932.939418025142, 4958.528939750718, 4961.900761984813, 5005.199168133154, 4396.846110386121, 4429.623482004065, 4443.400140980991, 4455.977720111378, 4477.462704475483, 5707.200991861143, 5729.799457018984, 5732.512736417451, 5733.483986441157, 5809.564282678816, 6142.314611233224, 6226.470749986302, 6274.780382686584, 6334.936650633527, 6339.334369891095, 5716.599838011382, 5725.08398656213, 5730.283714535683, 5769.682667453247, 5777.480026797609, 5402.891942161214, 5430.022595932691, 5434.239206511092, 5444.0, 5469.535089517356, 5238.0, 5641.12562552805, 6128.142060111677, 6319.052142178618, 6334.8733025338315, 6102.2771839767565, 6207.441330280297, 6286.15136956505, 6290.49676025926, 6309.526785491014, 6360.300366959538, 6401.8399540010005, 6411.96794016027, 6412.647304398303, 6413.583117097373, 6215.81352559424, 6244.500439964701, 6254.343293954069, 6277.748890044347, 6278.309385967631, 6117.279373342116, 6181.381861813783, 6230.034344676583, 6266.279377871603, 6322.083297921868, 5116.872078198075, 5966.820995370146, 6115.84710382244, 6120.846978825565, 6123.785667501616, 6085.087236914498, 6089.665068421279, 6107.0, 6133.693315334126, 6139.3553676863285, 4964.453910069961, 5792.594518383841, 5801.912971305518, 5853.678047707417, 5893.705314734332, 5974.641521508724, 6015.939840601713, 6027.0, 6036.456718895321, 6059.151718759465, 5754.51086657622, 5841.678707671119, 5872.588918775645, 5873.765049397976, 5897.67562784051], "value": 5694.1752710380615, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.51378566291015, 470.87521806721213, 474.51599368643963, 522.6289334572454, 523.8501489231137, 30879.382412351766, 32678.509822352684, 32695.50956735651, 32792.18019549509, 40821.795891020505, 40675.18649627006, 43197.56802431975, 43206.35190472138, 43214.0, 43246.78376608113, 9847.901520984788, 10006.449645269508, 10009.549570269355, 10026.248031397638, 10040.196784257241, 8864.0, 9039.502827344493, 9848.753781155458, 9966.551505182284, 11958.342291173982, 11961.803819618055, 12022.819657705142, 12295.508179672846, 12315.014798816073, 12326.568570100048, 10662.946685266563, 12255.632331030083, 12262.386880655931, 12267.999999999955, 12273.0, 12016.339101349422, 12095.0, 12096.395180240952, 12108.576199832964, 12115.515379384815, 40670.59329406727, 41000.179996400206, 41015.97460063492, 41061.589384106075, 41155.588444115776, 3586.5337506124038, 3646.8541258349633, 3885.708571857122, 4089.0799570096788, 4227.471566054243, 31843.52234716481, 32014.0, 32848.83575582107, 33070.0, 33160.668393316, 3269.1990462336644, 3361.8151001694896, 3382.120648631349, 3384.915377115567, 3391.101358140095, 457953.0, 459085.0, 460203.0, 460689.0, 469290.0, 59876.40123598708, 59996.700016499635, 60215.0, 60380.39619603835, 60470.09294860581, 62882.0, 63756.0, 63833.36166638367, 63842.36157638364, 63865.0, 16570.337186512403, 16785.580360491087, 16815.32738690463, 16817.32730690783, 16821.74767378466, 12985.0, 13004.739905201843, 13066.281354525592, 13076.869231307564, 13083.345832708326, 14925.955183137075, 15262.771058434133, 16962.83037169637, 17242.568935776457, 17305.56736081608, 5343.332083489564, 5421.267135976287, 6066.300496279202, 6315.526335524854, 6322.683865806691, 705.7776800307887, 869.8303830753069, 872.8865247517782, 998.2962011781676, 1004.1414590525142, 30208.813713968284, 30531.847340763597, 30554.0, 30858.919937802068, 31499.0, 1045.2213101239624, 1054.7415883108688, 1071.8499410082595, 1129.76274982253, 1213.6662417835166, 12957.870421295855, 15816.52550423489, 15879.603009924613, 16099.597510062342, 17008.404705835237, 644472.0, 645480.0, 647920.0, 648423.0, 649996.0, 58881.82236355249, 59156.0, 59240.81518369608, 59640.40359596435, 59827.0, 1235.9567415140427, 1270.0284282524062, 1271.364317841098, 1375.69734658375, 1393.8188035555313, 23731.881340593878, 31160.68839311578, 32159.839200803846, 32654.0, 32776.83611581927, 49938.0, 57354.3968404726, 57365.0, 57383.42616573781, 57394.42605574058, 606058.9091163548, 613544.0, 614426.0, 615092.1699306581, 615324.0, 26456.074037408598, 27028.72971270262, 27053.323666908484, 40622.0, 41013.38479922863, 37477.0, 39761.60238397695, 40754.79622601868, 41472.0, 48800.75599622121, 38602.6139738599, 39649.40525892172, 39777.00557486086, 41194.0, 41221.0, 36199.0, 39223.43106275717, 39527.0, 40315.0, 40837.795811020755, 38486.80756596199, 39256.411153832145, 39299.0, 39619.405708914965, 39635.0, 32819.0, 34426.0, 35134.64865351314, 35271.64728352786, 35478.645213547534, 25788.35529111787, 25941.740582594688, 25971.0, 26342.773074693752, 26745.732542674323], "value": 20442.97492213343, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.3131353965471, 379.08072923161336, 388.91638297765985, 398.99002524936856, 410.9486314210724, 19523.219071237134, 20999.055042523087, 21561.0, 21641.56716865664, 21953.67069493959, 20516.38450846477, 20714.067866945985, 20857.061432235547, 21504.784952150472, 21792.564148717054, 5096.439391666913, 5814.680192589406, 6105.32841387447, 6144.907826382608, 6178.443940045395, 3847.3844184930417, 4059.269331520325, 4060.3706425504092, 6125.081237814319, 6176.351483094278, 3809.4095415210686, 4189.601987811168, 6746.898796518056, 6764.120664313632, 6775.2208496023095, 6117.96941015292, 6315.305316415237, 6737.629430381327, 6756.797296081126, 6761.661916904135, 5969.880602387928, 5979.850503737398, 5983.880322393527, 5987.0, 5989.670568118751, 13362.799558006638, 13443.0, 13470.865291347158, 13637.249951252677, 13713.725725485434, 2192.8136108430804, 2228.6211344071385, 2363.751806060356, 2370.7392186859433, 2418.3107814273026, 9536.331142050114, 9800.607975680965, 9888.950555247178, 9910.454924979123, 10430.843537346946, 1832.3403574713093, 1877.1458986161265, 1927.1616846671743, 1950.444123424817, 2018.8082132197417, 66989.99999999952, 103646.0, 108798.45600772025, 117883.4105829474, 122358.38820805839, 10615.52230149645, 11421.486033128529, 11652.067834573214, 11653.53385864573, 11754.588589399502, 11672.649820505398, 14510.347034383478, 14521.564353069425, 14532.854671453362, 14550.927245363706, 7669.808254793563, 7909.0, 8162.83674326522, 8333.041700204432, 8354.331653467709, 7104.2895710429575, 8552.230299273095, 8628.654853805778, 8788.692395766118, 8790.956045219733, 3199.232184275757, 3904.8047597620007, 3932.7050471214775, 3978.3634618460906, 3995.5604883462797, 1892.706630472279, 1941.8155275249112, 1947.3087054095874, 1947.7857435682063, 1947.951301217481, 726.5604309392847, 821.6302663801314, 915.5595362491861, 969.7866469376726, 993.1062044160313, 19758.11088501021, 21184.682229766568, 22023.008964596633, 22056.33830985073, 22077.23382129426, 767.2672597669213, 948.9762755931018, 973.8928717841031, 976.6142373762324, 977.6480467031863, 10155.949220253851, 11501.287383640458, 11570.826437603442, 11578.305301681927, 11595.420228988516, 572980.1350993302, 579749.0, 581921.0, 590582.0, 595930.0, 122400.0, 132582.7079510617, 132668.0, 133267.0, 136704.0, 813.8413009463097, 912.3750231091724, 993.2749093162032, 993.9105480506789, 1039.8284283093355, 17237.74143387825, 20360.59278814386, 20458.488537786674, 20492.0, 20552.58894822125, 56838.0, 57351.0, 58778.412215879005, 59327.11009334777, 59510.70244648749, 69656.0, 82137.58931205305, 83085.0, 83987.5800620993, 84567.5771621138, 8029.237222463857, 8196.795080123045, 8364.205400486944, 8406.0, 8413.980644645364, 11191.216614837209, 11546.364949927834, 13139.60581182566, 13566.050376473551, 13625.727485450434, 9140.771480713036, 11236.381998990135, 11309.151813613847, 11471.885281147415, 11503.367314797431, 9758.80482390334, 11167.441627918734, 11376.0, 11508.59719909799, 11690.47392867306, 10413.895861041596, 10653.840192397276, 11345.886541134812, 11518.308901465938, 11814.409279536161, 6866.450683945172, 8081.272685458337, 8267.214614611603, 8363.163683631588, 8593.484390936565, 8451.704190353308, 10788.406637635006, 11536.769704482758, 11730.472128754056, 11747.647570572897], "value": 9354.752264090408, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.24219815804, 1489.4265707702534, 1535.470262759348, 1665.3088968078248, 1828.378351360538, 7423.925760742391, 7692.115406728228, 8354.415190936637, 8711.520866352348, 8732.738017859458, 8562.74311770648, 8642.91357086429, 8645.351598630097, 8648.524331161783, 8652.567371631425, 2376.8098552115835, 2402.519496100787, 2403.5072810073893, 2405.675233843426, 2406.9759302407015, 2348.4833336665993, 2386.3795413192606, 2391.390195500145, 2391.7488663690324, 2400.4238982644215, 2349.5066036132434, 2360.2565191964563, 2380.785729284364, 2390.0, 2391.689080419543, 2326.6626339180893, 2358.5518751437257, 2368.029108065685, 2368.988155059231, 2370.3718514593647, 2324.198151637699, 2349.8485741986474, 2350.471143992605, 2357.351728274738, 2357.669926210332, 8624.482531048157, 8697.739067827975, 8709.825803483958, 8800.0, 8817.735467935972, 5274.841754747364, 5467.890642187134, 5476.7535460904355, 5478.863028424282, 5487.067198576252, 3604.333198358312, 3617.439296908983, 3679.6136405677285, 3684.778913265213, 3691.0403295143187, 1112.7830073135742, 1113.3041848844473, 1113.4544073403974, 1116.9106471482262, 1122.186414849236, 11383.544658213661, 11426.457428247164, 11486.827697584544, 11503.712407189803, 11513.884861151366, 4363.367311739843, 4455.977720111378, 4611.769411529477, 4625.32932724753, 4625.930611040837, 8989.730308090637, 8997.0, 8999.235065019415, 9008.684696035738, 9017.684381046633, 5253.947460525423, 5254.238135470336, 5254.97372513135, 5258.6581872178185, 5259.184826351921, 4270.359446083113, 4415.955840441618, 4572.542745725467, 4885.340479035285, 4935.3090567320605, 3645.0, 3708.79601621908, 3789.1853251551047, 3794.2601192767697, 3795.1081495848453, 1157.8552680914886, 1175.3946717440524, 1182.373342128656, 1189.7501524679742, 1192.5766352944754, 748.8464864702723, 851.7998270406449, 884.2660591708994, 906.7325139083986, 935.8409070458039, 5587.413321601254, 5616.83751755577, 5744.224529688522, 5883.646981181143, 6120.326764055951, 857.6055014693311, 889.3818795936762, 894.6868595991351, 931.84624536952, 1001.5042553935848, 3374.6625337466057, 3944.6252606002386, 3958.762474251554, 4048.8987775305854, 4086.9386959195635, 10443.060124588823, 10744.355338679705, 10755.78488430227, 10757.0, 10780.245382823126, 10658.200634952409, 10735.141188704885, 10751.892481075245, 10757.46212689378, 10780.94609526963, 8154.2253485918745, 8289.958550207331, 8292.37807164465, 8304.086550479564, 8313.833723325499, 4790.688605240648, 5753.482186603226, 5846.269216347957, 6062.696865156637, 6168.074788781719, 9700.6119755209, 9873.062059104373, 9965.950170249102, 9998.350107243006, 10019.749506262402, 9475.384100033476, 10066.0, 10097.040781125781, 10231.897681023393, 10268.07587317145, 6930.410915072275, 7142.25006374344, 7453.9254607455405, 7555.962220188864, 7601.733939312097, 8056.154103819249, 8204.179582041748, 8354.582270886553, 8401.15988401155, 8417.873731893904, 7357.484976051625, 7416.0729908761405, 7462.589557574385, 7502.774916752506, 7614.961925190339, 6853.314668533107, 7044.084269044992, 7351.632418379167, 7359.668814903235, 7414.295641914009, 6673.499487538357, 6872.725090996304, 6962.617056061763, 6971.407430368273, 7028.437724982092, 4942.777575009061, 4981.2030075187795, 4987.326710893983, 5047.318611987482, 5105.668131571584, 4331.328644060176, 4791.520847915181, 4957.454679985271, 4985.376827896513, 5107.770150343169], "value": 4533.581713023011, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.227807950686, 302.61870043744887, 312.0762542873095, 318.79437762643096, 319.1096839816911, 5285.2072189171595, 5313.8937221255555, 5335.839924802257, 5341.30563026807, 5353.223782551532, 4878.853634390965, 4968.354113965189, 5047.0915235257735, 5242.161254199329, 5294.438140748479, 4018.403528841692, 4041.737287076345, 4062.796860156995, 4070.470838790953, 4088.4480595119576, 3470.8958731238104, 3955.782431966241, 4070.633642972132, 4078.796060196993, 4093.9795301023455, 3801.885943421702, 3964.108075682963, 3975.4036894465917, 4048.736832105904, 4056.269871423143, 3488.6511348865165, 3692.9261414771554, 3811.5235595550557, 3978.1844721831953, 4011.7994100295014, 3006.2033561106323, 3031.9382819682605, 3109.181129039492, 3481.485553784112, 3482.2687235680537, 4989.950100498985, 5156.74216289184, 5166.689998600096, 5194.441667499735, 5211.218317252443, 4174.645155161815, 4316.40293091555, 4498.865034048951, 4538.2738761797955, 4568.5203053679215, 5009.541508057831, 5416.808302173516, 5562.7774889004395, 5579.721013949327, 5585.385607583163, 3163.557102005721, 3787.0, 3794.677452416548, 3795.6014618464937, 3822.503074600312, 4963.553280204763, 5330.9733451332495, 5588.748506317225, 5736.590631934598, 5756.856078598069, 3827.8660246891227, 3831.9616803832164, 3836.9616303836606, 3840.7695538267794, 3860.189360234382, 3878.5151856017997, 3903.2388684206308, 3906.4726261954274, 3909.2963266611714, 3917.7257591968287, 4253.91525161082, 4315.8489452869, 4517.480489743656, 4549.272116461417, 4572.5993853226655, 5602.467765562265, 5609.467100625434, 5622.100463925753, 5624.606277560613, 5633.492985631313, 1258.5086672293362, 1277.4123903004722, 1311.0363882546321, 1342.4630147940902, 1343.6305016120646, 840.5755093677653, 861.814709837388, 862.6204470033165, 863.8208844926755, 864.8140649760206, 4602.286645569942, 4999.475055119233, 5104.132297509433, 5118.641695081308, 5159.948400516022, 4969.4782047884455, 5225.372955245395, 5689.800856969986, 5715.342735585377, 5717.627769335413, 4951.117318435783, 5082.695038297714, 5087.109755792721, 5122.462141475092, 5132.794688212504, 2298.4598619324447, 2366.455715185517, 2407.650890620885, 2537.61935709645, 2537.9111731089324, 4946.975265123651, 4954.5788607968725, 5010.874728131826, 5079.257201111433, 5081.441041485434, 4223.61365828306, 4550.590446859798, 4885.95114048855, 4928.334674818853, 4938.913439043405, 4430.889227769331, 6008.309044459856, 6046.5767396281835, 6057.878842423127, 6069.696515174135, 4823.348847905488, 4851.690043688114, 5787.421257874179, 5821.476067153976, 5831.7375718091935, 5572.412968416102, 5718.598943258929, 5723.6851973141875, 5769.653820770767, 5794.275715535558, 4760.6191504678845, 5815.924054050014, 5856.33978763273, 5950.523958083257, 5996.190514280516, 3340.4800815629314, 3436.9484457733656, 3653.415453527423, 3823.6941044716914, 3908.3746600543777, 4716.321269409866, 4791.592714619296, 5265.657732247391, 5522.972385138049, 5868.970655146697, 5217.765200565907, 5454.972725136349, 5546.833594992157, 5613.88772224545, 5636.435421882951, 4437.423134992559, 5578.541304391303, 5609.579281553818, 5620.26936498269, 5622.85942851432, 4541.455025396974, 5316.760745766373, 5509.834704958857, 5522.244873486068, 5529.861753456195, 4716.410448693913, 4883.536064073907, 5628.240187574789, 5642.633228840113, 5643.887122257614, 5247.921281180862, 5489.752961116679, 5507.421720719267, 5522.006038912874, 5524.281843360337], "value": 3999.0871226414743, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.3344856180875, 738.3760722189751, 746.1046743907312, 790.1032328307373, 808.1595141053305, 14385.921055920808, 14807.0, 14823.555293341204, 14829.332680029398, 14830.0, 14725.484608038721, 14938.925305373485, 14939.327730252138, 14966.625834354147, 15015.0, 585.979490717825, 698.5703792167806, 846.7840700621364, 847.8219573889476, 878.3149143667949, 855.2601999270648, 867.7136544940155, 872.6422166911567, 874.6282829797342, 877.4822854515835, 916.660835490866, 917.5870858113875, 918.8529835226365, 920.089111779337, 921.4287141971987, 725.1008749151015, 748.3265061444689, 752.9623518824092, 915.1260546178416, 918.0406475233397, 553.382977979554, 557.4954666027252, 660.4287291492824, 679.8980152977043, 684.9623270720108, 15143.621409464742, 15188.088714677044, 15201.163935983517, 15203.467878624195, 15266.465673701367, 14195.716085678332, 14405.495807646786, 14439.422423103064, 14578.562643120724, 14670.046446980912, 12910.453334661075, 13115.0, 13139.080264381497, 13145.474181032747, 13188.164366174891, 4612.654050946193, 4674.27548729944, 4717.221658426355, 4757.976210118962, 4855.562999330042, 111799.99999999919, 113357.43321283424, 113826.0, 113916.0, 114235.42882285535, 9644.95177524122, 9701.0, 11271.0, 11342.60300889465, 11366.0, 22556.887215563816, 22637.43406414853, 22695.09219631196, 22697.88651056767, 23261.3021609352, 7937.32532734724, 7964.362850971908, 7996.200379962073, 8002.159773223728, 8074.596270186467, 4260.510041345285, 4317.201317756162, 4381.474223093249, 4488.551144885485, 4565.109803588269, 3495.702865256482, 3645.198056427582, 3646.872359467406, 3652.086978255436, 3658.8536458541894, 661.3386613386614, 669.6350488983516, 674.3020973292619, 681.6148875885109, 690.1683471416931, 597.6384287506083, 628.7265039707651, 635.269440143829, 649.2306616659274, 650.3561474140566, 9174.0, 9189.402688825205, 9210.63157473707, 9210.95394523023, 9237.538123093818, 564.5257983294052, 574.2620732358898, 597.6115524908757, 620.1813606039984, 643.6073994863129, 4024.8188831502066, 4252.29837076885, 4416.447944006999, 4563.315502674559, 4584.575547276853, 166623.0, 167110.0, 168677.0, 169612.3038769621, 173557.0, 112906.0, 144034.0, 144693.27653361665, 145811.0, 147004.0, 119223.40388298003, 119734.0, 119734.4013279928, 121736.39131804461, 129077.99999999812, 8568.485890846569, 8673.783155420913, 8684.305255579664, 8819.559022048743, 8822.458254965282, 48936.0, 66443.0, 68551.31448685649, 69185.65407172932, 70253.29746702468, 80607.59696201481, 90849.54575227082, 93268.53365733128, 96730.0, 98081.50959245158, 11099.278546894426, 11320.433978301216, 12484.563040293546, 13210.405531750903, 13301.733965320833, 12384.31886246229, 15197.240137992834, 15251.313690884159, 15561.533154005398, 15717.528474145794, 14179.619777151658, 14275.571732848031, 14360.210188439736, 14374.85625143777, 14643.68136240651, 11917.940410297893, 13725.588232353046, 13863.584092477242, 14424.0, 14529.418823246951, 12400.069994750611, 13450.730985380042, 13554.25451600132, 13757.862421376058, 14325.212113333866, 11109.333439993627, 11170.27393219438, 11537.388114746156, 11850.58522951727, 11896.702582435508, 10950.178736594946, 11401.429928503707, 11542.884571154182, 11716.355600442057, 11762.529498820293], "value": 7940.134914572184, "unit": "score"}], "name": "dromaeo_css", "value": 7343.125122721345, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 111.0, 112.0, 108.0, 113.0, 111.0, 120.0, 151.0, 113.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 110.0, 154.0, 130.0, 138.0, 162.0, 116.0, 160.0, 108.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 184.0, 183.0, 183.0, 183.0, 183.0, 184.0, 181.0, 183.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 61.0, 88.0, 57.0, 55.0, 62.0, 67.0, 84.0, 56.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 68.0, 104.0, 70.0, 71.0, 68.0, 95.0, 72.0, 67.0, 103.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 87.0, 83.0, 87.0, 83.0, 88.0, 84.0, 86.0, 83.0], "value": 85.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 130.0, 150.0, 93.0, 93.0, 92.0, 93.0, 94.0, 93.0, 93.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 85.0, 84.0, 84.0, 84.0, 83.0, 85.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 56.0, 54.0, 54.0, 55.0, 55.0, 53.0, 55.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 75.0, 76.0, 74.0, 70.0, 72.0, 70.0, 72.0, 72.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 83.0, 86.0, 98.0, 105.0, 104.0, 105.0, 89.0, 85.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 151.0, 147.0, 153.0, 136.0, 137.0, 138.0, 137.0, 160.0, 144.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 169.0, 166.0, 168.0, 167.0, 168.0, 167.0, 166.0, 166.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 67.0, 68.0, 66.0, 68.0, 85.0, 86.0, 85.0], "value": 72.6, "unit": "score"}], "name": "kraken", "value": 1455.9999999999998, "alertThreshold": 2.0}]} 06:02:18 INFO - Return code: 0 06:02:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:02:18 INFO - # TBPL SUCCESS # 06:02:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:02:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:02:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:02:18 INFO - 2016-08-18 06:02:18.560 screenresolution[1939:18207] starting screenresolution argv=screenresolution get 06:02:18 INFO - 2016-08-18 06:02:18.563 screenresolution[1939:18207] Display 0: 1600x1200x32@60 06:02:18 INFO - 2016-08-18 06:02:18.570 screenresolution[1940:18211] starting screenresolution argv=screenresolution list 06:02:18 INFO - Available Modes on Display 0 06:02:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:02:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:02:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:02:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:02:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:02:18 INFO - Intel Iris: 06:02:18 INFO - Chipset Model: Intel Iris 06:02:18 INFO - Type: GPU 06:02:18 INFO - Bus: Built-In 06:02:18 INFO - VRAM (Dynamic, Max): 1536 MB 06:02:18 INFO - Vendor: Intel (0x8086) 06:02:18 INFO - Device ID: 0x0a2e 06:02:18 INFO - Revision ID: 0x0009 06:02:18 INFO - Displays: 06:02:18 INFO - Display: 06:02:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:02:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:02:18 INFO - Display Serial Number: 1600x1200 60 06:02:18 INFO - Main Display: Yes 06:02:18 INFO - Mirror: Off 06:02:18 INFO - Online: Yes 06:02:18 INFO - Rotation: Supported 06:02:18 INFO - Return code: 0 06:02:18 INFO - Running post-action listener: _resource_record_post_action 06:02:18 INFO - [mozharness: 2016-08-18 13:02:18.805524Z] Finished run-tests step (success) 06:02:18 INFO - Running post-run listener: _resource_record_post_run 06:02:18 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 811008; Write bytes: 701377024; Read time: 12; Write time: 2180 06:02:18 INFO - TinderboxPrint: CPU usage
53.7% 06:02:18 INFO - TinderboxPrint: I/O read bytes / time
811,008 / 12 06:02:18 INFO - TinderboxPrint: I/O write bytes / time
701,377,024 / 2,180 06:02:18 INFO - TinderboxPrint: CPU idle
2,246.9 (46.3%) 06:02:18 INFO - TinderboxPrint: CPU system
972.0 (20.0%) 06:02:18 INFO - TinderboxPrint: CPU user
1,630.8 (33.6%) 06:02:18 INFO - TinderboxPrint: Swap in / out
406,437,888 / 0 06:02:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400759296; Write bytes: 394884608; Read time: 29797; Write time: 780 06:02:18 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 757760; Write bytes: 292492288; Read time: 11; Write time: 1359 06:02:19 INFO - Running post-run listener: _upload_blobber_files 06:02:19 INFO - Blob upload gear active. 06:02:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:02:19 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 06:02:19 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'] 06:02:19 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 06:02:19 INFO - (blobuploader) - INFO - Open directory for files ... 06:02:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:02:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:02:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:20 INFO - (blobuploader) - INFO - Done attempting. 06:02:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:02:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:02:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:21 INFO - (blobuploader) - INFO - Done attempting. 06:02:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:02:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:02:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:23 INFO - (blobuploader) - INFO - Done attempting. 06:02:23 INFO - (blobuploader) - INFO - Iteration through files over. 06:02:23 INFO - Return code: 0 06:02:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:02:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:02:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c6a1e297b967c00058585ca74a86b9d6bcf47609a8b1257e864a2846ae3a377308f47c4b191f0bd1a44782c8ae084ceaa257f4ee17e21c971b11f9f3aa0bfab0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3e200e1c9ca732e7014c82064ce08291a244c3b579ae15e574418573170b11a318de9f8afb95997d87bba24340b2aec11c8b54caf6e3f1578fafd2544ad6c2e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4fe9bf43b0d22d62cb065f3b3967bf13cf1df24c82042845668bb4f01b46c951c33b87c7d73a345bbd70dba3a7497b4a1f9f2e324d3b3274935e9c01b23ba7b5"} 06:02:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:02:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:02:23 INFO - Contents: 06:02:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c6a1e297b967c00058585ca74a86b9d6bcf47609a8b1257e864a2846ae3a377308f47c4b191f0bd1a44782c8ae084ceaa257f4ee17e21c971b11f9f3aa0bfab0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3e200e1c9ca732e7014c82064ce08291a244c3b579ae15e574418573170b11a318de9f8afb95997d87bba24340b2aec11c8b54caf6e3f1578fafd2544ad6c2e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4fe9bf43b0d22d62cb065f3b3967bf13cf1df24c82042845668bb4f01b46c951c33b87c7d73a345bbd70dba3a7497b4a1f9f2e324d3b3274935e9c01b23ba7b5"} 06:02:23 INFO - Running post-run listener: copy_logs_to_upload_dir 06:02:23 INFO - Copying logs to upload dir... 06:02:23 INFO - mkdir: /builds/slave/test/build/upload/logs 06:02:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.192351 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-18 06:02:23.949745) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:02:23.953013) ========= 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.WkeLB2AgGG/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.Ttb02XApNB/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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/c6a1e297b967c00058585ca74a86b9d6bcf47609a8b1257e864a2846ae3a377308f47c4b191f0bd1a44782c8ae084ceaa257f4ee17e21c971b11f9f3aa0bfab0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3e200e1c9ca732e7014c82064ce08291a244c3b579ae15e574418573170b11a318de9f8afb95997d87bba24340b2aec11c8b54caf6e3f1578fafd2544ad6c2e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4fe9bf43b0d22d62cb065f3b3967bf13cf1df24c82042845668bb4f01b46c951c33b87c7d73a345bbd70dba3a7497b4a1f9f2e324d3b3274935e9c01b23ba7b5"} build_url:https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011104 build_url: 'https://queue.taskcluster.net/v1/task/QkQxtwL7Q5GXQ80cC-VUfw/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/c6a1e297b967c00058585ca74a86b9d6bcf47609a8b1257e864a2846ae3a377308f47c4b191f0bd1a44782c8ae084ceaa257f4ee17e21c971b11f9f3aa0bfab0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3e200e1c9ca732e7014c82064ce08291a244c3b579ae15e574418573170b11a318de9f8afb95997d87bba24340b2aec11c8b54caf6e3f1578fafd2544ad6c2e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4fe9bf43b0d22d62cb065f3b3967bf13cf1df24c82042845668bb4f01b46c951c33b87c7d73a345bbd70dba3a7497b4a1f9f2e324d3b3274935e9c01b23ba7b5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:02:23.986554) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:02:23.986939) ========= 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.WkeLB2AgGG/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.Ttb02XApNB/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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.005192 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:02:24.036462) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:02:24.036831) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:02:24.037235) ========= ========= Total master_lag: 0.18 =========