builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0230 starttime: 1471524317.27 results: success (0) buildid: 20160817125415 builduid: 1f7f4fa4813f414a918d3d31d9382594 revision: 16ed9b5cf1e8cc47297dd6b3d9d2fa908ebc6dd5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:17.270571) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:17.270998) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:17.271292) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:17.299762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:17.300113) ========= 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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:45:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.2M=0.001s 2016-08-18 05:45:17 (17.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.195684 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:17.518041) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:17.518369) ========= 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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.020720 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:17.566990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 05:45:17.567343) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 16ed9b5cf1e8cc47297dd6b3d9d2fa908ebc6dd5 --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 16ed9b5cf1e8cc47297dd6b3d9d2fa908ebc6dd5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:45:17,648 truncating revision to first 12 chars 2016-08-18 05:45:17,648 Setting DEBUG logging. 2016-08-18 05:45:17,648 attempt 1/10 2016-08-18 05:45:17,648 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/16ed9b5cf1e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 05:45:18,764 unpacking tar archive at: autoland-16ed9b5cf1e8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.400726 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 05:45:18.990149) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:18.990483) ========= script_repo_revision: 16ed9b5cf1e8cc47297dd6b3d9d2fa908ebc6dd5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:18.990860) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:18.991123) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:45:19.009400) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-18 05:45:19.009788) ========= /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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:45:19 INFO - MultiFileLogger online at 20160818 05:45:19 in /builds/slave/test 05:45:19 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:45:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:45:19 INFO - {'append_to_log': False, 05:45:19 INFO - 'base_work_dir': '/builds/slave/test', 05:45:19 INFO - 'blob_upload_branch': 'Autoland', 05:45:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:45:19 INFO - 'branch': 'Autoland', 05:45:19 INFO - 'buildbot_json_path': 'buildprops.json', 05:45:19 INFO - 'config_files': ('talos/mac_config.py',), 05:45:19 INFO - 'default_actions': ('clobber', 05:45:19 INFO - 'read-buildbot-config', 05:45:19 INFO - 'download-and-extract', 05:45:19 INFO - 'populate-webroot', 05:45:19 INFO - 'create-virtualenv', 05:45:19 INFO - 'install', 05:45:19 INFO - 'run-tests'), 05:45:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:45:19 INFO - 'download_minidump_stackwalk': True, 05:45:19 INFO - 'download_symbols': 'ondemand', 05:45:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:45:19 INFO - 'tooltool.py': '/tools/tooltool.py', 05:45:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:45:19 INFO - '/tools/misc-python/virtualenv.py')}, 05:45:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:45:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:45:19 INFO - 'installer_path': 'installer.exe', 05:45:19 INFO - 'log_level': 'info', 05:45:19 INFO - 'log_name': 'talos', 05:45:19 INFO - 'log_to_console': True, 05:45:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:45:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:45:19 INFO - 'opt_config_files': (), 05:45:19 INFO - 'pip_index': False, 05:45:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:19 INFO - 'cmd': ('bash', 05:45:19 INFO - '-c', 05:45:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:19 INFO - 'enabled': True, 05:45:19 INFO - 'halt_on_failure': False, 05:45:19 INFO - 'name': 'check_screen_resolution'},), 05:45:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:19 INFO - 'cmd': ('bash', 05:45:19 INFO - '-c', 05:45:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:19 INFO - 'enabled': True, 05:45:19 INFO - 'halt_on_failure': False, 05:45:19 INFO - 'name': 'check_screen_resolution'},), 05:45:19 INFO - 'run_cmd_checks_enabled': True, 05:45:19 INFO - 'sps_profile': False, 05:45:19 INFO - 'sps_profile_interval': 0, 05:45:19 INFO - 'suite': 'dromaeojs', 05:45:19 INFO - 'system_bits': '32', 05:45:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:45:19 INFO - 'title': 't-yosemite-r7-0230', 05:45:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:45:19 INFO - 'use_talos_json': True, 05:45:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:45:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:45:19 INFO - 'work_dir': 'build'} 05:45:19 INFO - [mozharness: 2016-08-18 12:45:19.200096Z] Running clobber step. 05:45:19 INFO - Running pre-action listener: _resource_record_pre_action 05:45:19 INFO - Running main action method: clobber 05:45:19 INFO - rmtree: /builds/slave/test/build 05:45:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:45:19 INFO - Running post-action listener: _resource_record_post_action 05:45:19 INFO - [mozharness: 2016-08-18 12:45:19.704751Z] Finished clobber step (success) 05:45:19 INFO - [mozharness: 2016-08-18 12:45:19.704941Z] Running read-buildbot-config step. 05:45:19 INFO - Running pre-action listener: _resource_record_pre_action 05:45:19 INFO - Running main action method: read_buildbot_config 05:45:19 INFO - Using buildbot properties: 05:45:19 INFO - { 05:45:19 INFO - "project": "", 05:45:19 INFO - "product": "firefox", 05:45:19 INFO - "script_repo_revision": "production", 05:45:19 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 05:45:19 INFO - "repository": "", 05:45:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 05:45:19 INFO - "buildid": "20160817125415", 05:45:19 INFO - "pgo_build": "False", 05:45:19 INFO - "basedir": "/builds/slave/test", 05:45:19 INFO - "buildnumber": 108, 05:45:19 INFO - "slavename": "t-yosemite-r7-0230", 05:45:19 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:45:19 INFO - "platform": "yosemite_r7", 05:45:19 INFO - "scheduler": "tests-autoland-macosx64-talos", 05:45:19 INFO - "branch": "autoland", 05:45:19 INFO - "repo_path": "integration/autoland", 05:45:19 INFO - "revision": "16ed9b5cf1e8cc47297dd6b3d9d2fa908ebc6dd5", 05:45:19 INFO - "stage_platform": "macosx64", 05:45:19 INFO - "builduid": "1f7f4fa4813f414a918d3d31d9382594", 05:45:19 INFO - "slavebuilddir": "test" 05:45:19 INFO - } 05:45:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:45:19 INFO - Running post-action listener: _resource_record_post_action 05:45:19 INFO - [mozharness: 2016-08-18 12:45:19.710872Z] Finished read-buildbot-config step (success) 05:45:19 INFO - [mozharness: 2016-08-18 12:45:19.711032Z] Running download-and-extract step. 05:45:19 INFO - Running pre-action listener: _resource_record_pre_action 05:45:19 INFO - Running main action method: download_and_extract 05:45:19 INFO - mkdir: /builds/slave/test/build/tests 05:45:19 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:45:19 INFO - trying https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:45:19 INFO - Downloading https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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:45:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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:45:21 INFO - Downloaded 1199 bytes. 05:45:21 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:45:21 INFO - Using the following test package requirements: 05:45:21 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:45:21 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:45:21 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:45:21 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:45:21 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:45:21 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:45:21 INFO - u'jsshell-mac.zip'], 05:45:21 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:45:21 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:45:21 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:45:21 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:45:21 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:45:21 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:45:21 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:45:21 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:45:21 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:45:21 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:45:21 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:45:21 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:21 INFO - trying https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:45:21 INFO - Downloading https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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:45:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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:45:22 INFO - Downloaded 16338632 bytes. 05:45: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:45:28 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:45: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:45:28 INFO - trying https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:45:28 INFO - Downloading https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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:45:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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:45:29 INFO - Downloaded 16338632 bytes. 05:45:29 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:45:36 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:45:36 INFO - trying https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:45:36 INFO - Downloading https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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:45:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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:45:37 INFO - Downloaded 14413719 bytes. 05:45:37 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:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:37 INFO - trying https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:45:37 INFO - Downloading https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:45:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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:45:40 INFO - Downloaded 111306066 bytes. 05:45:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:45:40 INFO - mkdir: /builds/slave/test/properties 05:45:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:45:40 INFO - Writing to file /builds/slave/test/properties/build_url 05:45:40 INFO - Contents: 05:45:40 INFO - build_url:https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:45:41 INFO - Running post-action listener: _resource_record_post_action 05:45:41 INFO - Running post-action listener: set_extra_try_arguments 05:45:41 INFO - [mozharness: 2016-08-18 12:45:41.256316Z] Finished download-and-extract step (success) 05:45:41 INFO - [mozharness: 2016-08-18 12:45:41.256440Z] Running populate-webroot step. 05:45:41 INFO - Running pre-action listener: _resource_record_pre_action 05:45:41 INFO - Running main action method: populate_webroot 05:45:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:45:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:45:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:45:41 INFO - '--tppagecycles', 05:45:41 INFO - '7'], 05:45:41 INFO - 'tests': ['tsvgm']}}, 05:45:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:45:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:45:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:45:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:45:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:41 INFO - 'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:45:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:41 INFO - 'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['damp', 'tps']}, 05:45:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:41 INFO - 'tests': ['damp', 'tps']}, 05:45:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['dromaeo_dom']}, 05:45:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:45:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['basic_compositor_video']}, 05:45:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:45:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['a11yr', 05:45:41 INFO - 'ts_paint', 05:45:41 INFO - 'tpaint', 05:45:41 INFO - 'sessionrestore', 05:45:41 INFO - 'sessionrestore_no_auto_restore', 05:45:41 INFO - 'tabpaint']}, 05:45:41 INFO - 'other-e10s': {'tests': ['a11yr', 05:45:41 INFO - 'ts_paint', 05:45:41 INFO - 'tpaint', 05:45:41 INFO - 'sessionrestore', 05:45:41 INFO - 'sessionrestore_no_auto_restore', 05:45:41 INFO - 'tabpaint']}, 05:45:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:45:41 INFO - 'ts_paint', 05:45:41 INFO - 'tpaint', 05:45:41 INFO - 'sessionrestore', 05:45:41 INFO - 'sessionrestore_no_auto_restore', 05:45:41 INFO - 'tabpaint']}, 05:45:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:45:41 INFO - 'ts_paint', 05:45:41 INFO - 'tpaint', 05:45:41 INFO - 'sessionrestore', 05:45:41 INFO - 'sessionrestore_no_auto_restore', 05:45:41 INFO - 'tabpaint']}, 05:45:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['a11yr', 05:45:41 INFO - 'ts_paint', 05:45:41 INFO - 'tpaint', 05:45:41 INFO - 'sessionrestore', 05:45:41 INFO - 'sessionrestore_no_auto_restore', 05:45:41 INFO - 'tabpaint']}, 05:45:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['a11yr', 05:45:41 INFO - 'ts_paint', 05:45:41 INFO - 'tpaint', 05:45:41 INFO - 'sessionrestore', 05:45:41 INFO - 'sessionrestore_no_auto_restore', 05:45:41 INFO - 'tabpaint']}, 05:45:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['tsvgx', 05:45:41 INFO - 'tsvgr_opacity', 05:45:41 INFO - 'tart', 05:45:41 INFO - 'tscrollx', 05:45:41 INFO - 'cart']}, 05:45:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:45:41 INFO - 'tsvgr_opacity', 05:45:41 INFO - 'tart', 05:45:41 INFO - 'tscrollx', 05:45:41 INFO - 'cart']}, 05:45:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:41 INFO - 'talos_options': ['--disable-e10s'], 05:45:41 INFO - 'tests': ['tp5o']}, 05:45:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:41 INFO - 'tests': ['tp5o']}, 05:45:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:41 INFO - 'talos_options': ['--disable-e10s', 05:45:41 INFO - '--xperf_path', 05:45:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:41 INFO - 'tests': ['tp5n']}, 05:45:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:45:41 INFO - 'talos_options': ['--xperf_path', 05:45:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:45:41 INFO - 'tests': ['tp5n']}}, 05:45:41 INFO - 'talos.zip': {'path': '', 05:45:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:45:41 INFO - Running post-action listener: _resource_record_post_action 05:45:41 INFO - [mozharness: 2016-08-18 12:45:41.275142Z] Finished populate-webroot step (success) 05:45:41 INFO - [mozharness: 2016-08-18 12:45:41.275256Z] Running create-virtualenv step. 05:45:41 INFO - Running pre-action listener: _resource_record_pre_action 05:45:41 INFO - Running main action method: create_virtualenv 05:45:41 INFO - Creating virtualenv /builds/slave/test/build/venv 05:45:41 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:45:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:45:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:45:41 INFO - Using real prefix '/tools/python27' 05:45:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:45:42 INFO - Installing distribute.............................................................................................................................................................................................done. 05:45:44 INFO - Installing pip.................done. 05:45:44 INFO - Return code: 0 05:45:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:44 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 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:45:44 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:45:44 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:45:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:45:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:44 INFO - 'HOME': '/Users/cltbld', 05:45:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:44 INFO - 'LOGNAME': 'cltbld', 05:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:44 INFO - 'MOZ_NO_REMOTE': '1', 05:45:44 INFO - 'NO_EM_RESTART': '1', 05:45:44 INFO - 'PAGER': '/bin/cat', 05:45:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:44 INFO - 'PWD': '/builds/slave/test', 05:45:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:44 INFO - 'SHELL': '/bin/bash', 05:45:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:45:44 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:45:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:44 INFO - 'USER': 'cltbld', 05:45:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:44 INFO - 'XPC_FLAGS': '0x0', 05:45:44 INFO - 'XPC_SERVICE_NAME': '0', 05:45:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:45 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:45:45 INFO - Cleaning up... 05:45:45 INFO - Return code: 0 05:45:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:45: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:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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:45:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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', '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 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:45: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:45: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 psutil>=3.1.1 05:45:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:45:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:45 INFO - 'HOME': '/Users/cltbld', 05:45:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:45 INFO - 'LOGNAME': 'cltbld', 05:45:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:45 INFO - 'MOZ_NO_REMOTE': '1', 05:45:45 INFO - 'NO_EM_RESTART': '1', 05:45:45 INFO - 'PAGER': '/bin/cat', 05:45:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:45 INFO - 'PWD': '/builds/slave/test', 05:45:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:45 INFO - 'SHELL': '/bin/bash', 05:45:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:45:45 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:45:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:45 INFO - 'USER': 'cltbld', 05:45:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:45 INFO - 'XPC_FLAGS': '0x0', 05:45:45 INFO - 'XPC_SERVICE_NAME': '0', 05:45:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:45 INFO - Downloading/unpacking psutil>=3.1.1 05:45: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:45: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:45: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:45: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:45: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:45: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:45:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:45:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:48 INFO - Installing collected packages: psutil 05:45:48 INFO - Running setup.py install for psutil 05:45:48 INFO - building 'psutil._psutil_osx' extension 05:45:48 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:45:49 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:45:49 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:45:49 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:45:49 INFO - building 'psutil._psutil_posix' extension 05:45:49 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:45:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:45:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:45:49 INFO - ^ 05:45:49 INFO - 1 warning generated. 05:45:49 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:45:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:45:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:45:49 INFO - Successfully installed psutil 05:45:49 INFO - Cleaning up... 05:45:49 INFO - Return code: 0 05:45:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:45: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:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:45: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:45: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 mozsystemmonitor==0.3 05:45:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:45:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:49 INFO - 'HOME': '/Users/cltbld', 05:45:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:49 INFO - 'LOGNAME': 'cltbld', 05:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:49 INFO - 'MOZ_NO_REMOTE': '1', 05:45:49 INFO - 'NO_EM_RESTART': '1', 05:45:49 INFO - 'PAGER': '/bin/cat', 05:45:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:49 INFO - 'PWD': '/builds/slave/test', 05:45:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:49 INFO - 'SHELL': '/bin/bash', 05:45:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:45:49 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:45:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:49 INFO - 'USER': 'cltbld', 05:45:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:49 INFO - 'XPC_FLAGS': '0x0', 05:45:49 INFO - 'XPC_SERVICE_NAME': '0', 05:45:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:45:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:45:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:45:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:45:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:45:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:45:52 INFO - Installing collected packages: mozsystemmonitor 05:45:52 INFO - Running setup.py install for mozsystemmonitor 05:45:52 INFO - Successfully installed mozsystemmonitor 05:45:52 INFO - Cleaning up... 05:45:52 INFO - Return code: 0 05:45:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:52 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 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:45:52 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:45:52 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:45:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:45:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:52 INFO - 'HOME': '/Users/cltbld', 05:45:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:52 INFO - 'LOGNAME': 'cltbld', 05:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:52 INFO - 'MOZ_NO_REMOTE': '1', 05:45:52 INFO - 'NO_EM_RESTART': '1', 05:45:52 INFO - 'PAGER': '/bin/cat', 05:45:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:52 INFO - 'PWD': '/builds/slave/test', 05:45:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:52 INFO - 'SHELL': '/bin/bash', 05:45:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:45:52 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:45:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:52 INFO - 'USER': 'cltbld', 05:45:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:52 INFO - 'XPC_FLAGS': '0x0', 05:45:52 INFO - 'XPC_SERVICE_NAME': '0', 05:45:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:53 INFO - Downloading/unpacking jsonschema==2.5.1 05:45:53 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:45:53 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:45:53 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:45:53 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:45:53 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:45:53 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:45:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:45:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:45:55 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:45:55 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:45:55 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:45:55 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:45:55 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:45:55 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:45:55 INFO - Downloading functools32-3.2.3-2.tar.gz 05:45:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:45:55 INFO - warning: no files found matching '*.txt' 05:45:55 INFO - no previously-included directories found matching 'build' 05:45:55 INFO - no previously-included directories found matching 'dist' 05:45:55 INFO - no previously-included directories found matching '.git*' 05:45:55 INFO - Installing collected packages: jsonschema, functools32 05:45:55 INFO - Running setup.py install for functools32 05:45:56 INFO - warning: no files found matching '*.txt' 05:45:56 INFO - no previously-included directories found matching 'build' 05:45:56 INFO - no previously-included directories found matching 'dist' 05:45:56 INFO - no previously-included directories found matching '.git*' 05:45:56 INFO - Successfully installed jsonschema functools32 05:45:56 INFO - Cleaning up... 05:45:56 INFO - Return code: 0 05:45:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:56 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 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:45:56 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:45:56 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:45:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:45:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:56 INFO - 'HOME': '/Users/cltbld', 05:45:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:56 INFO - 'LOGNAME': 'cltbld', 05:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:56 INFO - 'MOZ_NO_REMOTE': '1', 05:45:56 INFO - 'NO_EM_RESTART': '1', 05:45:56 INFO - 'PAGER': '/bin/cat', 05:45:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:56 INFO - 'PWD': '/builds/slave/test', 05:45:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:56 INFO - 'SHELL': '/bin/bash', 05:45:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:45:56 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:45:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:56 INFO - 'USER': 'cltbld', 05:45:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:56 INFO - 'XPC_FLAGS': '0x0', 05:45:56 INFO - 'XPC_SERVICE_NAME': '0', 05:45:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:45:56 INFO - Cleaning up... 05:45:56 INFO - Return code: 0 05:45:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:45:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:45:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:45:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:45:56 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 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:45:56 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:45:56 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:45:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:45:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:45:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:45:56 INFO - 'HOME': '/Users/cltbld', 05:45:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:45:56 INFO - 'LOGNAME': 'cltbld', 05:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:56 INFO - 'MOZ_NO_REMOTE': '1', 05:45:56 INFO - 'NO_EM_RESTART': '1', 05:45:56 INFO - 'PAGER': '/bin/cat', 05:45:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:56 INFO - 'PWD': '/builds/slave/test', 05:45:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:45:56 INFO - 'SHELL': '/bin/bash', 05:45:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:45:56 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:45:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:45:56 INFO - 'USER': 'cltbld', 05:45:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:45:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:45:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:56 INFO - 'XPC_FLAGS': '0x0', 05:45:56 INFO - 'XPC_SERVICE_NAME': '0', 05:45:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:45:56 INFO - Downloading/unpacking blobuploader==1.2.4 05:45:56 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:45:56 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:45:56 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:45:56 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:45:56 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:45:56 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:45:59 INFO - Downloading blobuploader-1.2.4.tar.gz 05:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:45:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:45:59 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:45:59 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:45:59 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:45:59 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:45:59 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:45:59 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:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:45:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:45:59 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:45:59 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:45:59 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:45:59 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:45:59 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:45:59 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:46:00 INFO - Downloading docopt-0.6.1.tar.gz 05:46:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:00 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:00 INFO - Running setup.py install for blobuploader 05:46:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:46:00 INFO - Running setup.py install for requests 05:46:01 INFO - Running setup.py install for docopt 05:46:01 INFO - Successfully installed blobuploader requests docopt 05:46:01 INFO - Cleaning up... 05:46:01 INFO - Return code: 0 05:46:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:01 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:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:01 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:46:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:01 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 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:46:01 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:46:01 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:46:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:46:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:01 INFO - 'HOME': '/Users/cltbld', 05:46:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:01 INFO - 'LOGNAME': 'cltbld', 05:46:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:01 INFO - 'MOZ_NO_REMOTE': '1', 05:46:01 INFO - 'NO_EM_RESTART': '1', 05:46:01 INFO - 'PAGER': '/bin/cat', 05:46:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:01 INFO - 'PWD': '/builds/slave/test', 05:46:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:01 INFO - 'SHELL': '/bin/bash', 05:46:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:46:01 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:46:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:01 INFO - 'USER': 'cltbld', 05:46:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:01 INFO - 'XPC_FLAGS': '0x0', 05:46:01 INFO - 'XPC_SERVICE_NAME': '0', 05:46:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:01 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-_yprdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:01 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-MnKdhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:01 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-CyMAqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:01 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-FEhf6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:01 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-wB64ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:02 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-YScfkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:02 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Kb_Pgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:02 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Sq6jlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:02 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Qw0y1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:02 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-PjzIBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:02 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-P4lo3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:02 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-H3YoB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:02 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-htwd7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:02 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-YTbwxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:03 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-MnCRNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:03 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-4suzkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:03 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Mykb2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:03 INFO - Running setup.py install for manifestparser 05:46:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:46:03 INFO - Running setup.py install for mozcrash 05:46:03 INFO - Running setup.py install for mozdebug 05:46:03 INFO - Running setup.py install for mozdevice 05:46:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:46:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:46:03 INFO - Running setup.py install for mozfile 05:46:04 INFO - Running setup.py install for mozhttpd 05:46:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:46:04 INFO - Running setup.py install for mozinfo 05:46:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:46:04 INFO - Running setup.py install for mozInstall 05:46:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:46:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:46:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:46:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:46:04 INFO - Running setup.py install for mozleak 05:46:04 INFO - Running setup.py install for mozlog 05:46:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:46:04 INFO - Running setup.py install for moznetwork 05:46:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:46:04 INFO - Running setup.py install for mozprocess 05:46:05 INFO - Running setup.py install for mozprofile 05:46:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:46:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:46:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:46:05 INFO - Running setup.py install for mozrunner 05:46:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:46:05 INFO - Running setup.py install for mozscreenshot 05:46:05 INFO - Running setup.py install for moztest 05:46:05 INFO - Running setup.py install for mozversion 05:46:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:46:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:05 INFO - Cleaning up... 05:46:05 INFO - Return code: 0 05:46:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46: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:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46: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:46:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:05 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 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:46:05 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:46:05 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:46:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:46:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:05 INFO - 'HOME': '/Users/cltbld', 05:46:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:05 INFO - 'LOGNAME': 'cltbld', 05:46:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:05 INFO - 'MOZ_NO_REMOTE': '1', 05:46:05 INFO - 'NO_EM_RESTART': '1', 05:46:05 INFO - 'PAGER': '/bin/cat', 05:46:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:05 INFO - 'PWD': '/builds/slave/test', 05:46:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:05 INFO - 'SHELL': '/bin/bash', 05:46:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:46:05 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:46:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:05 INFO - 'USER': 'cltbld', 05:46:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:05 INFO - 'XPC_FLAGS': '0x0', 05:46:05 INFO - 'XPC_SERVICE_NAME': '0', 05:46:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:06 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-vQxQG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:06 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:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:06 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-FvYeLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:06 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:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:06 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-q6QpO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:06 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:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:06 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-oKvljJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:06 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:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:06 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-eBWVwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:06 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:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:06 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-bMw_0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:06 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:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:06 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-dtHNbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:06 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:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:06 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-caatma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:07 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:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:07 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-3vdvIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:07 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:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:07 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-RWECZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:07 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:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:07 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-VTKHr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:07 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:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:07 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-S5Ixrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:07 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:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:07 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-hv3Yuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:07 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:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:07 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-_Kngh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:07 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:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:07 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-3TrGh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:07 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:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:07 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-lIrl_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:07 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:46:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:07 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-2WZWvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:07 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:46:07 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:46:07 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:46:07 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:46:07 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:46:07 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:46:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:46:07 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:46:07 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:46:07 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:46:07 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:46:07 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:46:07 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:46:10 INFO - Downloading blessings-1.6.tar.gz 05:46:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:46:10 INFO - Installing collected packages: blessings 05:46:10 INFO - Running setup.py install for blessings 05:46:10 INFO - Successfully installed blessings 05:46:10 INFO - Cleaning up... 05:46:10 INFO - Return code: 0 05:46:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:46:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:11 INFO - Reading from file tmpfile_stdout 05:46:11 INFO - Current package versions: 05:46:11 INFO - blessings == 1.6 05:46:11 INFO - blobuploader == 1.2.4 05:46:11 INFO - docopt == 0.6.1 05:46:11 INFO - functools32 == 3.2.3-2 05:46:11 INFO - jsonschema == 2.5.1 05:46:11 INFO - manifestparser == 1.1 05:46:11 INFO - mozInstall == 1.12 05:46:11 INFO - mozcrash == 0.17 05:46:11 INFO - mozdebug == 0.1 05:46:11 INFO - mozdevice == 0.48 05:46:11 INFO - mozfile == 1.2 05:46:11 INFO - mozhttpd == 0.7 05:46:11 INFO - mozinfo == 0.9 05:46:11 INFO - mozleak == 0.1 05:46:11 INFO - mozlog == 3.3 05:46:11 INFO - moznetwork == 0.27 05:46:11 INFO - mozprocess == 0.23 05:46:11 INFO - mozprofile == 0.28 05:46:11 INFO - mozrunner == 6.12 05:46:11 INFO - mozscreenshot == 0.1 05:46:11 INFO - mozsystemmonitor == 0.3 05:46:11 INFO - moztest == 0.7 05:46:11 INFO - mozversion == 1.4 05:46:11 INFO - psutil == 3.1.1 05:46:11 INFO - requests == 1.2.3 05:46:11 INFO - wsgiref == 0.1.2 05:46:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:11 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 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:46:11 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:46:11 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:46:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:46:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:11 INFO - 'HOME': '/Users/cltbld', 05:46:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:11 INFO - 'LOGNAME': 'cltbld', 05:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:11 INFO - 'MOZ_NO_REMOTE': '1', 05:46:11 INFO - 'NO_EM_RESTART': '1', 05:46:11 INFO - 'PAGER': '/bin/cat', 05:46:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:11 INFO - 'PWD': '/builds/slave/test', 05:46:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:11 INFO - 'SHELL': '/bin/bash', 05:46:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:46:11 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:46:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:11 INFO - 'USER': 'cltbld', 05:46:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:11 INFO - 'XPC_FLAGS': '0x0', 05:46:11 INFO - 'XPC_SERVICE_NAME': '0', 05:46:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:11 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:46:11 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:46:11 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:46:11 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:46:11 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:46:11 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:46:11 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:46:11 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:46:11 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:46:11 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:46:11 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:46:11 INFO - Cleaning up... 05:46:11 INFO - Return code: 0 05:46:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:11 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 0x102f01a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f96618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe6848b280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, '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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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:46:11 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:46:11 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:46:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:46:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:11 INFO - 'HOME': '/Users/cltbld', 05:46:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:11 INFO - 'LOGNAME': 'cltbld', 05:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:11 INFO - 'MOZ_NO_REMOTE': '1', 05:46:11 INFO - 'NO_EM_RESTART': '1', 05:46:11 INFO - 'PAGER': '/bin/cat', 05:46:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:11 INFO - 'PWD': '/builds/slave/test', 05:46:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:11 INFO - 'SHELL': '/bin/bash', 05:46:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:46:11 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:46:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:11 INFO - 'USER': 'cltbld', 05:46:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:11 INFO - 'XPC_FLAGS': '0x0', 05:46:11 INFO - 'XPC_SERVICE_NAME': '0', 05:46:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:46:11 INFO - Cleaning up... 05:46:11 INFO - Return code: 0 05:46:11 INFO - Running post-action listener: _resource_record_post_action 05:46:11 INFO - Running post-action listener: _start_resource_monitoring 05:46:11 INFO - Starting resource monitoring. 05:46:11 INFO - [mozharness: 2016-08-18 12:46:11.636891Z] Finished create-virtualenv step (success) 05:46:11 INFO - [mozharness: 2016-08-18 12:46:11.637921Z] Running install step. 05:46:11 INFO - Running pre-action listener: _resource_record_pre_action 05:46:11 INFO - Running main action method: install 05:46:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:11 INFO - Reading from file tmpfile_stdout 05:46:11 INFO - Detecting whether we're running mozinstall >=1.0... 05:46:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:46:11 INFO - Reading from file tmpfile_stdout 05:46:11 INFO - Output received: 05:46:11 INFO - Usage: mozinstall [options] installer 05:46:11 INFO - Options: 05:46:11 INFO - -h, --help show this help message and exit 05:46:11 INFO - -d DEST, --destination=DEST 05:46:11 INFO - Directory to install application into. [default: 05:46:11 INFO - "/builds/slave/test"] 05:46:11 INFO - --app=APP Application being installed. [default: firefox] 05:46:11 INFO - mkdir: /builds/slave/test/build/application 05:46:11 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:46:11 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:46:46 INFO - Reading from file tmpfile_stdout 05:46:46 INFO - Output received: 05:46:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:46:46 INFO - Running post-action listener: _resource_record_post_action 05:46:46 INFO - [mozharness: 2016-08-18 12:46:46.442469Z] Finished install step (success) 05:46:46 INFO - [mozharness: 2016-08-18 12:46:46.442602Z] Running run-tests step. 05:46:46 INFO - Running pre-action listener: _resource_record_pre_action 05:46:46 INFO - Running main action method: run_tests 05:46:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:46:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:46:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:46:46 INFO - 2016-08-18 05:46:46.457 screenresolution[6343:107345] starting screenresolution argv=screenresolution get 05:46:46 INFO - 2016-08-18 05:46:46.460 screenresolution[6343:107345] Display 0: 1600x1200x32@60 05:46:46 INFO - 2016-08-18 05:46:46.466 screenresolution[6344:107348] starting screenresolution argv=screenresolution list 05:46:46 INFO - Available Modes on Display 0 05:46:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:46:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:46:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:46:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:46:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:46:46 INFO - Intel Iris: 05:46:46 INFO - Chipset Model: Intel Iris 05:46:46 INFO - Type: GPU 05:46:46 INFO - Bus: Built-In 05:46:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:46:46 INFO - Vendor: Intel (0x8086) 05:46:46 INFO - Device ID: 0x0a2e 05:46:46 INFO - Revision ID: 0x0009 05:46:46 INFO - Displays: 05:46:46 INFO - Display: 05:46:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:46:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:46:46 INFO - Display Serial Number: 1600x1200 60 05:46:46 INFO - Main Display: Yes 05:46:46 INFO - Mirror: Off 05:46:46 INFO - Online: Yes 05:46:46 INFO - Rotation: Supported 05:46:46 INFO - Return code: 0 05:46:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:46:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:46:46 INFO - Python 2.7.3 05:46:46 INFO - Return code: 0 05:46:46 INFO - grabbing minidump binary from tooltool 05:46:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:46:46 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 0x102e65d30>, '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 0x102bcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102e5db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:46:46 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:46:46 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:46:46 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:46:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:46:46 INFO - Return code: 0 05:46:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:46:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:46:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:46:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:46:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:46:46 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/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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:46:46 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/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0230 --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:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Qah73gBkJJ/Render', 05:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:46 INFO - 'HOME': '/Users/cltbld', 05:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:46 INFO - 'LOGNAME': 'cltbld', 05:46:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:46:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:46 INFO - 'MOZ_NO_REMOTE': '1', 05:46:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:46:46 INFO - 'NO_EM_RESTART': '1', 05:46:46 INFO - 'PAGER': '/bin/cat', 05:46:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:46 INFO - 'PWD': '/builds/slave/test', 05:46:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:46 INFO - 'SHELL': '/bin/bash', 05:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oaaFwB0GFZ/Listeners', 05:46:46 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 05:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:46 INFO - 'USER': 'cltbld', 05:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:46 INFO - 'XPC_FLAGS': '0x0', 05:46:46 INFO - 'XPC_SERVICE_NAME': '0', 05:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:46 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/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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:46:47 INFO - mozversion application_buildid: 20160817125415 05:46:47 INFO - mozversion application_changeset: 16ed9b5cf1e8cc47297dd6b3d9d2fa908ebc6dd5 05:46:47 INFO - mozversion application_display_name: Nightly 05:46:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:46:47 INFO - mozversion application_name: Firefox 05:46:47 INFO - mozversion application_remotingname: firefox 05:46:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:46:47 INFO - mozversion application_vendor: Mozilla 05:46:47 INFO - mozversion application_version: 51.0a1 05:46:47 INFO - mozversion platform_buildid: 20160817125415 05:46:47 INFO - mozversion platform_changeset: 16ed9b5cf1e8cc47297dd6b3d9d2fa908ebc6dd5 05:46:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:46:47 INFO - mozversion platform_version: 51.0a1 05:46:47 INFO - using testdate: 1471524407 05:46:47 INFO - actual date: 1471524407 05:46:47 INFO - starting webserver on 'localhost:56945' 05:46:47 INFO - SUITE-START | Running 2 tests 05:46:47 INFO - TEST-START | dromaeo_css 05:46:47 INFO - operating with platform_type : mac_ 05:46:47 INFO - Initialising browser for dromaeo_css test... 05:46:47 INFO - TEST-INFO | started process 6354 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpGIjYo4/profile http://localhost:56945/getInfo.html) 05:46:49 INFO - PROCESS | 6354 | __metrics Screen width/height:1600/1200 05:46:49 INFO - PROCESS | 6354 | colorDepth:24 05:46:49 INFO - PROCESS | 6354 | Browser inner width/height: 1024/689 05:46:49 INFO - PROCESS | 6354 | __metrics 05:46:49 INFO - PROCESS | 6354 | 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:46:49 INFO - PROCESS | 6354 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:46:50 INFO - TEST-INFO | 6354: exit 0 05:46:50 INFO - Browser initialized. 05:46:50 INFO - Running cycle 1/1 for dromaeo_css test... 05:46:50 INFO - TEST-INFO | started process 6357 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpGIjYo4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:02 INFO - PROCESS | 6357 | RSS: Main: 228290560 05:47:02 INFO - PROCESS | 6357 | 05:47:53 INFO - PROCESS | 6357 | 1471524473695 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:50:06 INFO - PROCESS | 6357 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56945/tests/dromaeo/cssquery-ext.html) 05:50:07 INFO - PROCESS | 6357 | RSS: Main: 367628288 05:50:07 INFO - PROCESS | 6357 | 05:53:10 INFO - PROCESS | 6357 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-ext.html (next: http://localhost:56945/tests/dromaeo/cssquery-jquery.html) 05:53:11 INFO - PROCESS | 6357 | RSS: Main: 370647040 05:53:11 INFO - PROCESS | 6357 | 05:56:13 INFO - PROCESS | 6357 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56945/tests/dromaeo/cssquery-mootools.html) 05:56:14 INFO - PROCESS | 6357 | RSS: Main: 344006656 05:56:14 INFO - PROCESS | 6357 | 05:59:17 INFO - PROCESS | 6357 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56945/tests/dromaeo/cssquery-prototype.html) 05:59:17 INFO - PROCESS | 6357 | RSS: Main: 354537472 05:59:17 INFO - PROCESS | 6357 | 06:02:21 INFO - PROCESS | 6357 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56945/tests/dromaeo/cssquery-yui.html) 06:02:21 INFO - PROCESS | 6357 | RSS: Main: 335859712 06:02:21 INFO - PROCESS | 6357 | 06:05:25 INFO - PROCESS | 6357 | Cycle 1(1): loaded http://localhost:56945/tests/dromaeo/cssquery-yui.html (next: http://localhost:56945/tests/dromaeo/cssquery-dojo.html) 06:05:25 INFO - PROCESS | 6357 | RSS: Main: 518279168 06:05:25 INFO - PROCESS | 6357 | 06:05:25 INFO - PROCESS | 6357 | __start_tp_report 06:05:25 INFO - PROCESS | 6357 | _x_x_mozilla_page_load 06:05:25 INFO - PROCESS | 6357 | _x_x_mozilla_page_load_details 06:05:25 INFO - PROCESS | 6357 | |i|pagename|runs| 06:05:25 INFO - PROCESS | 6357 | |0;dojo.html;3264.396086723956;3283.540304357389;3291.687289707477;3297.6537463566324;3309.139623697838;5696.25948626679;5724.112762521816;5801.390853960336;5840.737166827487;5877.764889404424;4795.616350691945;5830.7084645767645;5880.353161152269;5922.407759224076;5947.910781338283;5221.530062294392;5246.055709972204;5297.576193904498;5313.734313284321;5340.706261155635;5357.624966252364;5384.246205531209;5414.052540805363;5420.972895135519;5460.863478413032;5214.113600687892;5243.4232234454175;5271.446498117719;5309.33633295838;5320.361556613192;3907.8046097695287;4047.4535937648334;5290.153575427951;5304.893902121975;5307.840764777025;5199.53204211619;5223.921641175385;5230.555402790767;5272.789088436458;5336.599755018389;5851.61964472308;5926;5943.257092863392;5947.464728174442;5992.131140984577;6084.255793581239;6114.2365411686305;6135.2939121233285;6148.415900489446;6169.691515424211;6191.566590338678;6226.62640241587;6239.407256310643;6253.093301471306;6270.937290627081;5844.649321040752;6073.42302481268;6090.969545152335;6093.329733729286;6103.6948152592195;6204.968975155095;6283.340249273851;6300;6390.137331460193;6396.968015159894;6055.969720151459;6145.66198859067;6154.7230374633245;6161.599496032743;6189.381061893865;6143.262808463013;6225.221847269091;6230.750769969241;6232.407921247474;6246.031865060922;6136.570440069241;6139.676133582418;6153.646197836469;6153.7384836108495;6192.8761424771255;6101.762223876893;6162;6170.290416602058;6176.258848938066;6179.134921111048;5076.543111120017;5079.87300317495;5083.339165908408;5086.23706444029;5112.8721781955;4275.657947364203;4318.963448772272;4458.643308535309;4515.571020753023;4562.041971186088;5936.5250779937505;5943.940560594338;5945.346011938684;5945.881082378328;5973.37279585646;6131.774283661862;6137.714085983886;6182.721777519932;6185.721642526007;6256.7810126645345;5900.911486327708;5947.256592925884;5951.642901425928;5994.298711747274;6035.185249991283;4683.8360657376825;5571.582131340166;5573.637713548606;5575.916361254584;5586.944130558723;6173.166622505994;6259.18630578031;6303.023031430135;6317.399847014526;6418.775342862978;6251.249850017845;6252.249730032425;6257.43683068526;6268.153799237045;6275.3724627536885;6292.811215663537;6348.460380867493;6351.968240158954;6360.936390636034;6396.136521569715;6137.662428566293;6150.200473938359;6173.506119510339;6174.413430724074;6237.157983672146;6124.601900876429;6261.092141639346;6262.968685156545;6282.120503129657;6297.433231009231;6216.285127210248;6219.502439804895;6245.5628106032145;6256.906146407717;6262.4990000800735;6027.156198132177;6112.442689633966;6151.907721384094;6171.814845554641;6225.3774622537385;5126.769295381791;5684.431556844283;5768.365479797135;6129.632222066691;6135.815925522242;6210.254769427697;6236.251649802053;6267.122602835516;6272.8431789203805;6281.96859015702;5906.379830117776;6012.969935150296;6046.879062418815;6059.21230240066;6095.817125486243 06:05:25 INFO - PROCESS | 6357 | |1;ext.html;506.5592934147291;520.6953931949812;529.1533546325878;539.4201233673801;543.3398420918583;30840.38319233614;43662;44688.32967505486;44847.103057938824;45049.7747511262;44107.77946110265;44483.66549003527;44515;44614.55385446145;44703.77648111763;8838.29293656509;9880.308378413514;10038.598456061749;10062.849057264148;10079.496025198747;8826.293896488298;12125.272483650964;12398.566050188245;12418.633950265463;12573.811392829115;12399.93800031003;12438.315892625902;12476.81284780729;12497.375131243401;12501.49994000239;12317.568885089024;12368.13423060386;12382.75234495314;12383.690407739788;12387.256764594063;12164.14850960433;12224.205426647239;12238.632841014694;12238.959688426494;12244.326562039085;41301.96745081367;41739.7913010436;41758;41784.58215417837;41793.582064179274;3385;3418.7094097001786;3425.143714071482;3543.450765131409;3872.554656214515;31705.890293839606;32732.672673273202;32864.67135328664;32917.341653167045;33043.6695633043;3237.093613788143;3364.6971772540587;3415.846286917095;3448.4654878493875;3449.1032331593715;421318;423754;424630;425333;429450.8527457374;60718.08922866161;61227.69386153041;61324.69337653283;61337;61366.69316653388;59953.700231498566;64324.678376608754;64572.677136614955;64706;64843;16307.91846040786;16701.749473757904;16709.331626734795;16731.330746770236;16746.83253167453;11668.124890633237;11833.940830295793;12762.234265944073;12858.485660573659;12944.546940857213;17294.048827314367;17544.385946491813;17548.947063175994;17602.735958960628;17869.195886185153;5822.621529600562;6741.224759152662;6759.5606285591275;6773.830654233683;6789.694463749143;883.439016224702;960.6301573893961;987.8419452887504;998.6854140945579;1038.2887721910365;31437.056888293388;31491.37017259642;31839.84080079631;31965.200869977973;32661.346773064404;1009.248110157937;1036.3419228789774;1041.9788607165021;1077.256692881909;1108.3737688206138;14829.406823727146;15730.270205927938;15898.284577194054;16071.276792544364;16077.517674469784;582277;594761;596481;597801;600074;59524.70237648784;59642;59672.403275967554;59698;59775.701121494116;1243.5150291386367;1296.2287438973794;1296.7082406458471;1396.5321617258332;1422.5945605502382;25549.74450255548;27674.308142296602;31443.52834707527;31873.521897172024;33090.66909330876;57706.7114664424;58435.415645843;58843.41156588551;58900.11649825342;58913.41086589251;556540;559299;560477;562806;563795;26450.206493805217;26650.200493985212;26843.731562684123;27526.724732752416;27617.723822761516;49101.50898490969;49158;49949;50411.74794126006;50551;38849.6115038846;40157;40214.79892600518;41806.79096604497;42107.36838947357;39238;39745.20509589734;39917.600823991386;41116;41330;38409.615903840604;38952.80523597364;39512.60487395204;39595.01012474711;39746.40380394238;32791.67208327886;34771.9568412948;35548.28903421865;36008.099797505274;36035.27929441449;25524.23427297184;25876.094336698126;25936.74063259419;26445.33886652772;26553.33616659599 06:05:25 INFO - PROCESS | 6357 | |2;jquery.html;327.8164228032302;350.9982450087749;374.6366024955794;383.20676200265456;400.9097952960584;15950.28223729932;17294.82705172948;20800.687989680166;21553.892230538866;21681.457963550918;19990.50023749407;20500.384988450332;21027.789722102774;21073.57852842942;21078.262260820873;5426.294581704373;5516.393196748354;5594.944050559503;5717.371089180187;5722.256106706122;3941.527016757993;5516.531094856942;5610;5617.494425501704;5624.493795558399;5964.612300200495;6229.37706229378;6278.717457714391;6292.150559674431;6313.558050936436;6011.30869949957;6142.293636231846;6200.534959877982;6262.12330273762;6286.119943207955;6017.127516510125;6045.546584006217;6054.182685337511;6059.0608455689435;6087.878242435171;13228.007899407583;13533.458661653522;13567.59297221075;13646.86353136466;13841.031127821057;2234.4637287051155;2238.82089432845;2320.5474932388192;2387.283814855554;2445.8532488050773;9642.855357169648;9976.850347244797;10251.538680759384;10359.378437293712;10462.947685261524;1801.864860135495;1842.649896519657;1865.9720104198448;1929.9324523641606;1971.073595410155;112772.43613782043;117723.41138294253;123609;123756.76243237543;124757;11190.104791616814;11328.660140195807;11573.479193436315;11636.709082273012;11920.88079119215;14715.705685886436;14730.926345368205;14784.852151478563;14791.408343666348;14795.334209960361;8344.874826877602;8593;8598.613062412207;8739.694110706221;8765.649374025095;8555.743327700178;8559.101294364127;8582.399232053696;8755.124487551195;8772.38593298463;3906.3163946309282;4447.933281000787;4549.499555048942;4573.428321459817;4696.225122854691;1856.981430185708;1904.961900761977;1916.6741653918866;1916.7220752990738;1917.8945158016443;696.2524361396638;751.4777229825219;818.417891552154;865.7706057398383;913.2465715784505;20985.580288394147;21334.893325533274;21466.570668586854;21474.677879831816;21523.892380537996;923.2890674180843;940.3323640215524;975.8341082016075;980.8675828763169;1057.8254587992894;9430.331598025106;10391.48042597867;11004;11324.263922844988;11360.318380897173;547135;559932;562709;572302;576611;134809;136117.3194134023;139007;140806;143314;961.0677642686583;973.600823662295;974.88301403832;995.7411073121041;995.7510622344414;17972.19125139398;18271.451856444328;18405.355812546495;18410.63178736391;18438;45883.770581146884;49421.50578494169;49611.503884960686;49757;51294.48705512897;69857.60284794174;71966.64016679882;72797;74948;75464.62267688627;8104.5137291762685;8154.918450815416;8178.877316840371;8365.539895305574;8418.242358187792;12049.939750301543;13081.607551773463;13109.60671179866;13466.19202847793;13568.321583920568;10932.961368669963;11049.834252486058;11145.331280123219;11438.428078596204;11529.654110376701;11191.888081119085;11361;11603.013873385587;11681.415929203677;11711.88737069977;11215.775684486101;11385.324448754702;11450.713732156762;11595.710107247049;11636.418179090842;6791.966040169965;7712.8457430852195;8300.2114799094;8390.958045209734;8595.484270943763;10757.031867131996;11430.714232144262;11585.768284634092;11609.071274297867;11638.243514171552 06:05:25 INFO - PROCESS | 6357 | |3;mootools.html;1508.705802368538;1543.1512668032578;1729.7664815249937;1874.9062546872653;1889.971650425243;8334.083250842403;8875.467471951688;8884.02275749667;8890.022097569263;8892.777680557982;7676.501027433213;7888.881666775003;8331.291840193591;8541.701040463586;8582.48505089695;2331.895064722087;2388.1283331584023;2391.1152873436854;2397.820163487737;2400.9039638414442;2352.96470552941;2380.702412198475;2389.438481956739;2400.303911865557;2403.2790162951046;2383.5709572276983;2387.7254115776645;2401.423658321999;2402.4714562796157;2408.1691816323428;2053.507158282009;2360.3627020704484;2377.95244095118;2380.726216485109;2389.319044072431;2338.3686404670643;2367.846090004144;2368.3131891751373;2371.253055287596;2376.3346263046374;8713.520756358397;8786.912130878673;8827.823443531093;8837.867431988525;8839.29285657146;5213.139831927728;5367.275417818583;5376.677399356051;5389.9191512127345;5393.514583687448;3609.458581212813;3628;3683.557973043252;3688.133288677159;3692.778433293984;1091.5524634899652;1092.055372103133;1093.6609651008214;1115.8326251062406;1133.0482394788417;11450.484728187252;11544;11573.826392604033;11587.768244635061;11611.767764644745;4500.212462818994;4693.084848454587;4766.618670506351;4796.232602783538;4802.543758343022;9916.107550320507;9918.206543476648;9918.752031199134;9927.30508864387;9931.404115753077;5351.732413379315;5362.168863826113;5365.382980957162;5365.382980957162;5379.1393377059485;4428.7121337112985;4562.68061235717;4594.609458196091;4657.510961349009;4932.482089380564;3487.459443786217;3887.814216663888;3911.7848518331766;3975.7614543127506;4009.759414435085;1136.8976792088752;1275.2667216350635;1276.9425375857923;1287.3949243855373;1291.7739395605731;729.4893574497767;783.1972228465868;793.8568461415543;903.6882275615034;926.3978414030934;5165.870853228625;5841.182234487175;5872.0898260769645;5950.8809823803285;6011.248593925759;813.3168138763467;886.5212785096105;949.4635530925005;965.1699538396973;969.9757506062405;3764.3036038333;3899.883003509899;3973.4238535412205;4040.8181631826637;4048.473698419187;8873.866891996626;10786.514606842711;10789.298695584763;10798.028177464066;10807.621733239455;10529.105026072717;10603.787924241473;10667.146628269875;10777.299475534066;10782;8037.598120093972;8093;8149.225823546753;8150.103488616248;8177.632006559719;5677.460641239076;5711.828645140652;5773.884522309614;5829.125631155361;5881.2942446906645;9862.21102311799;9927.503624818875;9973.850392244267;9989.900100998897;10002.949985250027;9643.132118109404;10096.798064038825;10116.241281904035;10239.976002399999;10285.794284114425;6887.5178737487895;7160.892586611101;7166.928330716626;7462.216467270859;7657.6554055066545;7824.804379890548;7894.171112033384;7993.160718124514;8242.887210226543;8353.164683531599;7370.152432470334;7484.251574842472;7569.697212111454;7601.163871974189;7744.49660772048;7283.417326613964;7382.187959324363;7438.218987006287;7466.440016998638;7574.242575742382;6783.559068660521;6936.132983377078;6947.756828510978;6948.583085014915;6951.617661028692;4854.388271383712;4981.676191047571;4986.351774269322;4997.475265097112;5036.143855544639;4808.2787581863;4833.202521583899;4951.777170027285;5082.695038297714;5090.745462726923 06:05:25 INFO - PROCESS | 6357 | |4;prototype.html;283.6043719011978;289.9188227296358;312.9030000699783;314.85988735012927;316.90492852144365;4715.386999690039;5069.200433845982;5192.71440070796;5379.677219366836;5389.541888939445;3959.9208015839736;5052.722100284483;5198.194279886614;5269.8155564555245;5273.947260527393;3440.6043305019866;3609.4405367168;4117.958820411795;4130.047016383285;4169.874903752892;3514.947275790865;3726.360401423379;4036.7577945323374;4134.7519148851015;4142.130152667944;3608.783472991629;3890.396988466792;3932.8820135395986;4084.4077608746716;4085.20338533986;3561.8397172127316;3714.23858109087;3928.273269445162;4106.28140075487;4132.586741325874;3205.5031470122167;3380.7633465657414;3549;3558.430651095812;3585.2969839326374;4426.911461770779;4837.467878533358;4957.305977163164;5170.302009228743;5197.428282888879;4594.264917613199;4609.631229501631;4627.5603817637275;4628.861134165981;4631.814727410933;5304.1732023858485;5442.863928401822;5629.943700563023;5694.829155125353;5721.828345149652;3248.668046101111;3742.4760533525327;3826.330392181385;3830.751001184914;3848.9615103849164;4963.510946715971;5234.555062819665;5282.735863206824;5703.201551782754;5833;3764.849406023783;3844.519435070616;3845.7115716321387;3850.306944749916;3850.845966161322;3293.4685371302457;3888.591697871739;3897.8635747748694;3921.8039098044983;3940.0937784309767;4146.647534959562;4395.857077159962;4531.456225252925;4617.191991401492;4684.367610372556;5319.441458646864;5630.4369563043365;5637.605367624227;5669.866026794658;5729.88540229193;1141.3551343490901;1199.3883119609063;1296.8962483001337;1325.4764368074557;1326.7678156322604;759.8708219602681;798.5448294472211;811.4360519439051;817.235884448043;817.8282560662209;3801.0497375656087;4332.935005974913;4407.187782568056;4443.178012067778;4485.237509623372;4679.812807487662;4741.407324084489;4760.523947605281;4775.976120119377;4784.760761961888;3678.7191940996754;4105.378375541704;4253.787310634518;4347.2610955618;4355.911022244439;2364.4325361913016;2440.511897620483;2492.513959777826;2509.623556466508;2525.7979361651023;4084;4471.43913208532;4559.658025648023;4838.653253172209;4905.332187056418;4202.978985105055;4350.71720338177;4715.976420117878;4935;4991.425986011583;4314.556007312473;4335.544767799399;4607.539246075433;4888.02239552091;4934.506549345037;4133.359329303963;4736.447664251811;4751.572358487752;4754.263089221176;4796;4391.407160033355;4605.907881842278;4719.253876065877;4770.379850619397;4781.497942716104;4163.97918010408;4859.416869975626;5520.613557050968;6064.696765161636;6122.969385153224;3916.706247031427;3927.6661483773055;3928.8428462862307;3930.9803450983704;3936.8386326033315;5792.623479473821;5821.417858214314;5838.970805145947;5880.647451086189;5881.5588830837005;4638.397008388888;4906.9018619626695;5349.144136938071;5757.481826635623;5779.156860687595;5740.483356497935;5750.894516960794;5758.8848223034465;5759.308882934075;5766.8846623066465;5011.173156429149;5638.830835074954;5644.576656750843;5649.717514124195;5675.716214189357;5176.482351764794;5674.517665998436;5769.249997500298;5784;5795;5141.742912854417;5365.59758018158;5625.521830644441;5654.6428857074825;5717.197554054614 06:05:25 INFO - PROCESS | 6357 | |5;yui.html;682.3279070115937;728.9161746399166;745.2808040241166;760.9429292803035;808.4179390838598;15608.765868511968;17056.829431705693;17671.381501647415;17702.645947081066;17702.822971770278;16538.586535336624;16609.252583633734;16612.335506579722;16624.66750664986;16643;581.9272590926134;841.7600983719641;853.5817449449787;855.7561095087901;859.6647307550061;699.1749735312329;839.5046922315832;852.4586887326531;864.148813418782;877.5963056993759;908.0329449136682;909.4270609515995;909.749818799832;911.6262332443699;911.731039343392;902.6479672927563;906.9977674669461;907.2333877873186;907.3059109781022;908.4776253654176;523.6465385864526;534.636447215893;535.1517844216921;539.8812261302508;542.7449098923499;15113.924430377778;15591.922040389838;15634.140122293269;15673.68652626941;15700.900936934418;14109.929450352682;14460.638484037878;14627.853721462861;14695.70608587833;14739.778903316459;11811.704707382298;11895.821562676567;12001.699957501045;12020.879791202064;12416.937915310456;3848.903777405559;3917.353636649978;3926.725129240954;3963.2667956427863;3970.404439334123;112588;115977;118558;118602;121787.99999999911;11186.216964812547;11327.309938601373;11453.828192577119;11532.827007594893;11804.822927656092;21990.81133235704;22300.88849555774;22325.55348893013;22350.552988940457;22369.440763980707;8099.190080991972;8171.42800003994;8183.626748242943;8529;8539.359548033923;3842.481265029241;4410.448693913261;4429.546270800907;4560.84037058708;4774.689645173053;3649.087728067983;3815.5802861685193;3820.6561409472724;3823.2544653792243;3825.101101241206;567.6962824888665;682.9453643708491;686.629220221085;690.2579726793717;694.1184695436868;508.98473045808686;597.086338285793;661.5534514202894;679.3104998426601;698.7798843364324;9343.972163062057;9430.858537121949;9569.61721531145;9632;9688.806223875483;529.5525281137433;529.9920501192486;604.3261763134074;636.2587585462976;674.6458109492555;4525.343825145402;4648.5063693476295;4655.74393408359;4665.136949664323;4807.4471435784635;89497.10502895017;112325.15122731493;118952.40523797326;135967;140183;118733;125146;126513.73486265019;127905.72094279125;132683.33658331647;93394;100074;100099.99800004104;106706;108994.91005090006;9406.740765869048;9681.180797097704;9761.581198289494;9795.461249631338;9799.125416824505;56829.431705682415;59807.80384392375;60870.086948694916;60984.69507652433;61773.07340389808;84783.15216848;84860.57569712112;86713.56643216744;88519.55740221516;89151;12645;13192;13341.265040666445;13478.595642130751;13575.587933921317;14573.708525829636;14884.553463396116;14901.92549037248;15026.398944042116;15579.532614021597;11829.82255266189;13764.31178441054;14088.436462541793;14137.434502620192;14277.928610356881;12722.554710585086;13188.472461101448;13385.66535836612;13508.594742157751;13782.448702051806;13276.734465310832;13687.657808554866;13726.519571814939;13858.369632856255;13952.232627205602;11231.887681123084;11239.887601124212;11536.711582210512;11557.608467931057;11622.302661840316;8182.713605023796;10653.201009924442;11473.770524589294;11609.245399049034;11668.474918628512 06:05:25 INFO - PROCESS | 6357 | __end_tp_report 06:05:25 INFO - PROCESS | 6357 | __start_cc_report 06:05:25 INFO - PROCESS | 6357 | _x_x_mozilla_cycle_collect,1373 06:05:25 INFO - PROCESS | 6357 | __end_cc_report 06:05:25 INFO - PROCESS | 6357 | __startTimestamp1471525525513__endTimestamp 06:05:25 INFO - PROCESS | 6357 | 06:05:25 INFO - PROCESS | 6357 | ------- Summary: start ------- 06:05:25 INFO - PROCESS | 6357 | Number of tests: 6 06:05:25 INFO - PROCESS | 6357 | 06:05:25 INFO - PROCESS | 6357 | [#0] dojo.html Cycles:165 Average:5782.49 Median:6090.97 stddev:674.41 (11.1%) stddev-sans-first:646.90 06:05:25 INFO - PROCESS | 6357 | Values: 3264.4 3283.5 3291.7 3297.7 3309.1 5696.3 5724.1 5801.4 5840.7 5877.8 4795.6 5830.7 5880.4 5922.4 5947.9 5221.5 5246.1 5297.6 5313.7 5340.7 5357.6 5384.2 5414.1 5421.0 5460.9 5214.1 5243.4 5271.4 5309.3 5320.4 3907.8 4047.5 5290.2 5304.9 5307.8 5199.5 5223.9 5230.6 5272.8 5336.6 5851.6 5926.0 5943.3 5947.5 5992.1 6084.3 6114.2 6135.3 6148.4 6169.7 6191.6 6226.6 6239.4 6253.1 6270.9 5844.6 6073.4 6091.0 6093.3 6103.7 6205.0 6283.3 6300.0 6390.1 6397.0 6056.0 6145.7 6154.7 6161.6 6189.4 6143.3 6225.2 6230.8 6232.4 6246.0 6136.6 6139.7 6153.6 6153.7 6192.9 6101.8 6162.0 6170.3 6176.3 6179.1 5076.5 5079.9 5083.3 5086.2 5112.9 4275.7 4319.0 4458.6 4515.6 4562.0 5936.5 5943.9 5945.3 5945.9 5973.4 6131.8 6137.7 6182.7 6185.7 6256.8 5900.9 5947.3 5951.6 5994.3 6035.2 4683.8 5571.6 5573.6 5575.9 5586.9 6173.2 6259.2 6303.0 6317.4 6418.8 6251.2 6252.2 6257.4 6268.2 6275.4 6292.8 6348.5 6352.0 6360.9 6396.1 6137.7 6150.2 6173.5 6174.4 6237.2 6124.6 6261.1 6263.0 6282.1 6297.4 6216.3 6219.5 6245.6 6256.9 6262.5 6027.2 6112.4 6151.9 6171.8 6225.4 5126.8 5684.4 5768.4 6129.6 6135.8 6210.3 6236.3 6267.1 6272.8 6282.0 5906.4 6013.0 6046.9 6059.2 6095.8 06:05:25 INFO - PROCESS | 6357 | 06:05:25 INFO - PROCESS | 6357 | [#1] ext.html Cycles:180 Average:67822.41 Median:35778.19 stddev:141557.31 (395.7%) stddev-sans-first:141864.18 06:05:25 INFO - PROCESS | 6357 | Values: 506.6 520.7 529.2 539.4 543.3 30840.4 43662.0 44688.3 44847.1 45049.8 44107.8 44483.7 44515.0 44614.6 44703.8 8838.3 9880.3 10038.6 10062.8 10079.5 8826.3 12125.3 12398.6 12418.6 12573.8 12399.9 12438.3 12476.8 12497.4 12501.5 12317.6 12368.1 12382.8 12383.7 12387.3 12164.1 12224.2 12238.6 12239.0 12244.3 41302.0 41739.8 41758.0 41784.6 41793.6 3385.0 3418.7 3425.1 3543.5 3872.6 31705.9 32732.7 32864.7 32917.3 33043.7 3237.1 3364.7 3415.8 3448.5 3449.1 421318.0 423754.0 424630.0 425333.0 429450.9 60718.1 61227.7 61324.7 61337.0 61366.7 59953.7 64324.7 64572.7 64706.0 64843.0 16307.9 16701.7 16709.3 16731.3 16746.8 11668.1 11833.9 12762.2 12858.5 12944.5 17294.0 17544.4 17548.9 17602.7 17869.2 5822.6 6741.2 6759.6 6773.8 6789.7 883.4 960.6 987.8 998.7 1038.3 31437.1 31491.4 31839.8 31965.2 32661.3 1009.2 1036.3 1042.0 1077.3 1108.4 14829.4 15730.3 15898.3 16071.3 16077.5 582277.0 594761.0 596481.0 597801.0 600074.0 59524.7 59642.0 59672.4 59698.0 59775.7 1243.5 1296.2 1296.7 1396.5 1422.6 25549.7 27674.3 31443.5 31873.5 33090.7 57706.7 58435.4 58843.4 58900.1 58913.4 556540.0 559299.0 560477.0 562806.0 563795.0 26450.2 26650.2 26843.7 27526.7 27617.7 49101.5 49158.0 49949.0 50411.7 50551.0 38849.6 40157.0 40214.8 41806.8 42107.4 39238.0 39745.2 39917.6 41116.0 41330.0 38409.6 38952.8 39512.6 39595.0 39746.4 32791.7 34772.0 35548.3 36008.1 36035.3 25524.2 25876.1 25936.7 26445.3 26553.3 06:05:25 INFO - PROCESS | 6357 | 06:05:25 INFO - PROCESS | 6357 | [#2] jquery.html Cycles:180 Average:33913.95 Median:2353.92 stddev:94782.99 (4026.6%) stddev-sans-first:95015.33 06:05:25 INFO - PROCESS | 6357 | Values: 327.8 351.0 374.6 383.2 400.9 15950.3 17294.8 20800.7 21553.9 21681.5 19990.5 20500.4 21027.8 21073.6 21078.3 5426.3 5516.4 5594.9 5717.4 5722.3 3941.5 5516.5 5610.0 5617.5 5624.5 5964.6 6229.4 6278.7 6292.2 6313.6 6011.3 6142.3 6200.5 6262.1 6286.1 6017.1 6045.5 6054.2 6059.1 6087.9 13228.0 13533.5 13567.6 13646.9 13841.0 2234.5 2238.8 2320.5 2387.3 2445.9 9642.9 9976.9 10251.5 10359.4 10462.9 1801.9 1842.6 1866.0 1929.9 1971.1 112772.4 117723.4 123609.0 123756.8 124757.0 11190.1 11328.7 11573.5 11636.7 11920.9 14715.7 14730.9 14784.9 14791.4 14795.3 8344.9 8593.0 8598.6 8739.7 8765.6 8555.7 8559.1 8582.4 8755.1 8772.4 3906.3 4447.9 4549.5 4573.4 4696.2 1857.0 1905.0 1916.7 1916.7 1917.9 696.3 751.5 818.4 865.8 913.2 20985.6 21334.9 21466.6 21474.7 21523.9 923.3 940.3 975.8 980.9 1057.8 9430.3 10391.5 11004.0 11324.3 11360.3 547135.0 559932.0 562709.0 572302.0 576611.0 134809.0 136117.3 139007.0 140806.0 143314.0 961.1 973.6 974.9 995.7 995.8 17972.2 18271.5 18405.4 18410.6 18438.0 45883.8 49421.5 49611.5 49757.0 51294.5 69857.6 71966.6 72797.0 74948.0 75464.6 8104.5 8154.9 8178.9 8365.5 8418.2 12049.9 13081.6 13109.6 13466.2 13568.3 10933.0 11049.8 11145.3 11438.4 11529.7 11191.9 11361.0 11603.0 11681.4 11711.9 11215.8 11385.3 11450.7 11595.7 11636.4 6792.0 7712.8 8300.2 8391.0 8595.5 10757.0 11430.7 11585.8 11609.1 11638.2 06:05:25 INFO - PROCESS | 6357 | 06:05:25 INFO - PROCESS | 6357 | [#3] mootools.html Cycles:180 Average:5676.51 Median:5016.81 stddev:3160.66 (63.0%) stddev-sans-first:3154.00 06:05:25 INFO - PROCESS | 6357 | Values: 1508.7 1543.2 1729.8 1874.9 1890.0 8334.1 8875.5 8884.0 8890.0 8892.8 7676.5 7888.9 8331.3 8541.7 8582.5 2331.9 2388.1 2391.1 2397.8 2400.9 2353.0 2380.7 2389.4 2400.3 2403.3 2383.6 2387.7 2401.4 2402.5 2408.2 2053.5 2360.4 2378.0 2380.7 2389.3 2338.4 2367.8 2368.3 2371.3 2376.3 8713.5 8786.9 8827.8 8837.9 8839.3 5213.1 5367.3 5376.7 5389.9 5393.5 3609.5 3628.0 3683.6 3688.1 3692.8 1091.6 1092.1 1093.7 1115.8 1133.0 11450.5 11544.0 11573.8 11587.8 11611.8 4500.2 4693.1 4766.6 4796.2 4802.5 9916.1 9918.2 9918.8 9927.3 9931.4 5351.7 5362.2 5365.4 5365.4 5379.1 4428.7 4562.7 4594.6 4657.5 4932.5 3487.5 3887.8 3911.8 3975.8 4009.8 1136.9 1275.3 1276.9 1287.4 1291.8 729.5 783.2 793.9 903.7 926.4 5165.9 5841.2 5872.1 5950.9 6011.2 813.3 886.5 949.5 965.2 970.0 3764.3 3899.9 3973.4 4040.8 4048.5 8873.9 10786.5 10789.3 10798.0 10807.6 10529.1 10603.8 10667.1 10777.3 10782.0 8037.6 8093.0 8149.2 8150.1 8177.6 5677.5 5711.8 5773.9 5829.1 5881.3 9862.2 9927.5 9973.9 9989.9 10002.9 9643.1 10096.8 10116.2 10240.0 10285.8 6887.5 7160.9 7166.9 7462.2 7657.7 7824.8 7894.2 7993.2 8242.9 8353.2 7370.2 7484.3 7569.7 7601.2 7744.5 7283.4 7382.2 7438.2 7466.4 7574.2 6783.6 6936.1 6947.8 6948.6 6951.6 4854.4 4981.7 4986.4 4997.5 5036.1 4808.3 4833.2 4951.8 5082.7 5090.7 06:05:25 INFO - PROCESS | 6357 | 06:05:25 INFO - PROCESS | 6357 | [#4] prototype.html Cycles:180 Average:4306.07 Median:4628.21 stddev:1333.94 (28.8%) stddev-sans-first:1303.07 06:05:25 INFO - PROCESS | 6357 | Values: 283.6 289.9 312.9 314.9 316.9 4715.4 5069.2 5192.7 5379.7 5389.5 3959.9 5052.7 5198.2 5269.8 5273.9 3440.6 3609.4 4118.0 4130.0 4169.9 3514.9 3726.4 4036.8 4134.8 4142.1 3608.8 3890.4 3932.9 4084.4 4085.2 3561.8 3714.2 3928.3 4106.3 4132.6 3205.5 3380.8 3549.0 3558.4 3585.3 4426.9 4837.5 4957.3 5170.3 5197.4 4594.3 4609.6 4627.6 4628.9 4631.8 5304.2 5442.9 5629.9 5694.8 5721.8 3248.7 3742.5 3826.3 3830.8 3849.0 4963.5 5234.6 5282.7 5703.2 5833.0 3764.8 3844.5 3845.7 3850.3 3850.8 3293.5 3888.6 3897.9 3921.8 3940.1 4146.6 4395.9 4531.5 4617.2 4684.4 5319.4 5630.4 5637.6 5669.9 5729.9 1141.4 1199.4 1296.9 1325.5 1326.8 759.9 798.5 811.4 817.2 817.8 3801.0 4332.9 4407.2 4443.2 4485.2 4679.8 4741.4 4760.5 4776.0 4784.8 3678.7 4105.4 4253.8 4347.3 4355.9 2364.4 2440.5 2492.5 2509.6 2525.8 4084.0 4471.4 4559.7 4838.7 4905.3 4203.0 4350.7 4716.0 4935.0 4991.4 4314.6 4335.5 4607.5 4888.0 4934.5 4133.4 4736.4 4751.6 4754.3 4796.0 4391.4 4605.9 4719.3 4770.4 4781.5 4164.0 4859.4 5520.6 6064.7 6123.0 3916.7 3927.7 3928.8 3931.0 3936.8 5792.6 5821.4 5839.0 5880.6 5881.6 4638.4 4906.9 5349.1 5757.5 5779.2 5740.5 5750.9 5758.9 5759.3 5766.9 5011.2 5638.8 5644.6 5649.7 5675.7 5176.5 5674.5 5769.2 5784.0 5795.0 5141.7 5365.6 5625.5 5654.6 5717.2 06:05:25 INFO - PROCESS | 6357 | 06:05:25 INFO - PROCESS | 6357 | [#5] yui.html Cycles:180 Average:23981.20 Median:3824.18 stddev:36813.95 (962.7%) stddev-sans-first:36875.66 06:05:25 INFO - PROCESS | 6357 | Values: 682.3 728.9 745.3 760.9 808.4 15608.8 17056.8 17671.4 17702.6 17702.8 16538.6 16609.3 16612.3 16624.7 16643.0 581.9 841.8 853.6 855.8 859.7 699.2 839.5 852.5 864.1 877.6 908.0 909.4 909.7 911.6 911.7 902.6 907.0 907.2 907.3 908.5 523.6 534.6 535.2 539.9 542.7 15113.9 15591.9 15634.1 15673.7 15700.9 14109.9 14460.6 14627.9 14695.7 14739.8 11811.7 11895.8 12001.7 12020.9 12416.9 3848.9 3917.4 3926.7 3963.3 3970.4 112588.0 115977.0 118558.0 118602.0 121788.0 11186.2 11327.3 11453.8 11532.8 11804.8 21990.8 22300.9 22325.6 22350.6 22369.4 8099.2 8171.4 8183.6 8529.0 8539.4 3842.5 4410.4 4429.5 4560.8 4774.7 3649.1 3815.6 3820.7 3823.3 3825.1 567.7 682.9 686.6 690.3 694.1 509.0 597.1 661.6 679.3 698.8 9344.0 9430.9 9569.6 9632.0 9688.8 529.6 530.0 604.3 636.3 674.6 4525.3 4648.5 4655.7 4665.1 4807.4 89497.1 112325.2 118952.4 135967.0 140183.0 118733.0 125146.0 126513.7 127905.7 132683.3 93394.0 100074.0 100100.0 106706.0 108994.9 9406.7 9681.2 9761.6 9795.5 9799.1 56829.4 59807.8 60870.1 60984.7 61773.1 84783.2 84860.6 86713.6 88519.6 89151.0 12645.0 13192.0 13341.3 13478.6 13575.6 14573.7 14884.6 14901.9 15026.4 15579.5 11829.8 13764.3 14088.4 14137.4 14277.9 12722.6 13188.5 13385.7 13508.6 13782.4 13276.7 13687.7 13726.5 13858.4 13952.2 11231.9 11239.9 11536.7 11557.6 11622.3 8182.7 10653.2 11473.8 11609.2 11668.5 06:05:25 INFO - PROCESS | 6357 | -------- Summary: end -------- 06:05:25 INFO - PROCESS | 6357 | 06:05:26 INFO - TEST-INFO | 6357: exit 0 06:05:26 INFO - TEST-OK | dromaeo_css | took 1119117ms 06:05:26 INFO - TEST-START | kraken 06:05:26 INFO - operating with platform_type : mac_ 06:05:26 INFO - Initialising browser for kraken test... 06:05:26 INFO - TEST-INFO | started process 6360 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpur9bh4/profile http://localhost:56945/getInfo.html) 06:05:28 INFO - PROCESS | 6360 | __metrics Screen width/height:1600/1200 06:05:28 INFO - PROCESS | 6360 | colorDepth:24 06:05:28 INFO - PROCESS | 6360 | Browser inner width/height: 1024/689 06:05:28 INFO - PROCESS | 6360 | __metrics 06:05:28 INFO - PROCESS | 6360 | 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:05:28 INFO - PROCESS | 6360 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:05:29 INFO - TEST-INFO | 6360: exit 0 06:05:29 INFO - Browser initialized. 06:05:29 INFO - Running cycle 1/1 for kraken test... 06:05:29 INFO - TEST-INFO | started process 6361 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpur9bh4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:05:41 INFO - PROCESS | 6361 | RSS: Main: 228507648 06:05:41 INFO - PROCESS | 6361 | 06:05:44 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56945/tests/kraken/driver.html?testName=audio-beat-detection) 06:05:45 INFO - PROCESS | 6361 | RSS: Main: 285495296 06:05:45 INFO - PROCESS | 6361 | 06:05:47 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56945/tests/kraken/driver.html?testName=audio-dft) 06:05:48 INFO - PROCESS | 6361 | RSS: Main: 293576704 06:05:48 INFO - PROCESS | 6361 | 06:05:52 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56945/tests/kraken/driver.html?testName=audio-fft) 06:05:52 INFO - PROCESS | 6361 | RSS: Main: 383299584 06:05:52 INFO - PROCESS | 6361 | 06:05:54 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56945/tests/kraken/driver.html?testName=audio-oscillator) 06:05:55 INFO - PROCESS | 6361 | RSS: Main: 289132544 06:05:55 INFO - PROCESS | 6361 | 06:05:57 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56945/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:05:58 INFO - PROCESS | 6361 | RSS: Main: 294232064 06:05:58 INFO - PROCESS | 6361 | 06:06:00 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56945/tests/kraken/driver.html?testName=imaging-darkroom) 06:06:01 INFO - PROCESS | 6361 | RSS: Main: 333451264 06:06:01 INFO - PROCESS | 6361 | 06:06:04 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56945/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:05 INFO - PROCESS | 6361 | RSS: Main: 333074432 06:06:05 INFO - PROCESS | 6361 | 06:06:07 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56945/tests/kraken/driver.html?testName=json-parse-financial) 06:06:08 INFO - PROCESS | 6361 | RSS: Main: 329744384 06:06:08 INFO - PROCESS | 6361 | 06:06:10 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56945/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:10 INFO - PROCESS | 6361 | RSS: Main: 300339200 06:06:10 INFO - PROCESS | 6361 | 06:06:12 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:13 INFO - PROCESS | 6361 | RSS: Main: 307122176 06:06:13 INFO - PROCESS | 6361 | 06:06:15 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:16 INFO - PROCESS | 6361 | RSS: Main: 305758208 06:06:16 INFO - PROCESS | 6361 | 06:06:19 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:06:19 INFO - PROCESS | 6361 | RSS: Main: 312598528 06:06:19 INFO - PROCESS | 6361 | 06:06:22 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:06:23 INFO - PROCESS | 6361 | RSS: Main: 315174912 06:06:23 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | Cycle 1(1): loaded http://localhost:56945/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56945/tests/kraken/driver.html?testName=ai-astar) 06:06:25 INFO - PROCESS | 6361 | RSS: Main: 358879232 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | __start_tp_report 06:06:25 INFO - PROCESS | 6361 | _x_x_mozilla_page_load 06:06:25 INFO - PROCESS | 6361 | _x_x_mozilla_page_load_details 06:06:25 INFO - PROCESS | 6361 | |i|pagename|runs| 06:06:25 INFO - PROCESS | 6361 | |0;ai-astar;101;112;122;164;149;113;107;111;111;112 06:06:25 INFO - PROCESS | 6361 | |1;audio-beat-detection;144;106;107;168;105;109;130;106;144;108 06:06:25 INFO - PROCESS | 6361 | |2;audio-dft;185;184;202;258;188;184;184;183;184;182 06:06:25 INFO - PROCESS | 6361 | |3;audio-fft;81;58;63;88;59;58;66;66;83;58 06:06:25 INFO - PROCESS | 6361 | |4;audio-oscillator;85;70;136;99;95;71;98;71;67;102 06:06:25 INFO - PROCESS | 6361 | |5;imaging-gaussian-blur;87;85;87;85;89;85;87;84;89;84 06:06:25 INFO - PROCESS | 6361 | |6;imaging-darkroom;104;146;115;93;94;93;99;120;93;93 06:06:25 INFO - PROCESS | 6361 | |7;imaging-desaturate;85;85;84;84;83;83;85;84;84;84 06:06:25 INFO - PROCESS | 6361 | |8;json-parse-financial;55;54;57;54;55;56;56;55;55;54 06:06:25 INFO - PROCESS | 6361 | |9;json-stringify-tinderbox;66;68;78;77;74;70;70;74;74;73 06:06:25 INFO - PROCESS | 6361 | |10;stanford-crypto-aes;88;89;84;85;87;84;84;84;88;86 06:06:25 INFO - PROCESS | 6361 | |11;stanford-crypto-ccm;144;135;147;147;144;141;134;143;233;154 06:06:25 INFO - PROCESS | 6361 | |12;stanford-crypto-pbkdf2;176;172;173;173;172;170;177;171;171;178 06:06:25 INFO - PROCESS | 6361 | |13;stanford-crypto-sha256-iterative;68;68;69;69;68;67;67;67;69;66 06:06:25 INFO - PROCESS | 6361 | __end_tp_report 06:06:25 INFO - PROCESS | 6361 | __start_cc_report 06:06:25 INFO - PROCESS | 6361 | _x_x_mozilla_cycle_collect,2135 06:06:25 INFO - PROCESS | 6361 | __end_cc_report 06:06:25 INFO - PROCESS | 6361 | __startTimestamp1471525585537__endTimestamp 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | ------- Summary: start ------- 06:06:25 INFO - PROCESS | 6361 | Number of tests: 14 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#0] ai-astar Cycles:10 Average:120.20 Median:112.50 stddev:20.14 (17.9%) stddev-sans-first:20.12 06:06:25 INFO - PROCESS | 6361 | Values: 101.0 112.0 122.0 164.0 149.0 113.0 107.0 111.0 111.0 112.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:119.50 stddev:22.45 (18.8%) stddev-sans-first:22.44 06:06:25 INFO - PROCESS | 6361 | Values: 144.0 106.0 107.0 168.0 105.0 109.0 130.0 106.0 144.0 108.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#2] audio-dft Cycles:10 Average:193.40 Median:184.50 stddev:23.42 (12.7%) stddev-sans-first:24.65 06:06:25 INFO - PROCESS | 6361 | Values: 185.0 184.0 202.0 258.0 188.0 184.0 184.0 183.0 184.0 182.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#3] audio-fft Cycles:10 Average:68.00 Median:66.00 stddev:11.59 (17.6%) stddev-sans-first:11.29 06:06:25 INFO - PROCESS | 6361 | Values: 81.0 58.0 63.0 88.0 59.0 58.0 66.0 66.0 83.0 58.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:78.00 stddev:21.35 (27.4%) stddev-sans-first:22.59 06:06:25 INFO - PROCESS | 6361 | Values: 85.0 70.0 136.0 99.0 95.0 71.0 98.0 71.0 67.0 102.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:1.87 (2.2%) stddev-sans-first:1.96 06:06:25 INFO - PROCESS | 6361 | Values: 87.0 85.0 87.0 85.0 89.0 85.0 87.0 84.0 89.0 84.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:93.00 stddev:17.45 (18.8%) stddev-sans-first:18.50 06:06:25 INFO - PROCESS | 6361 | Values: 104.0 146.0 115.0 93.0 94.0 93.0 99.0 120.0 93.0 93.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:0.74 (0.9%) stddev-sans-first:0.71 06:06:25 INFO - PROCESS | 6361 | Values: 85.0 85.0 84.0 84.0 83.0 83.0 85.0 84.0 84.0 84.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.99 (1.8%) stddev-sans-first:1.05 06:06:25 INFO - PROCESS | 6361 | Values: 55.0 54.0 57.0 54.0 55.0 56.0 56.0 55.0 55.0 54.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:74.00 stddev:3.84 (5.2%) stddev-sans-first:3.30 06:06:25 INFO - PROCESS | 6361 | Values: 66.0 68.0 78.0 77.0 74.0 70.0 70.0 74.0 74.0 73.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.50 stddev:1.97 (2.3%) stddev-sans-first:1.94 06:06:25 INFO - PROCESS | 6361 | Values: 88.0 89.0 84.0 85.0 87.0 84.0 84.0 84.0 88.0 86.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:145.50 stddev:28.98 (19.9%) stddev-sans-first:30.58 06:06:25 INFO - PROCESS | 6361 | Values: 144.0 135.0 147.0 147.0 144.0 141.0 134.0 143.0 233.0 154.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:173.00 stddev:2.75 (1.6%) stddev-sans-first:2.74 06:06:25 INFO - PROCESS | 6361 | Values: 176.0 172.0 173.0 173.0 172.0 170.0 177.0 171.0 171.0 178.0 06:06:25 INFO - PROCESS | 6361 | 06:06:25 INFO - PROCESS | 6361 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:1.09 06:06:25 INFO - PROCESS | 6361 | Values: 68.0 68.0 69.0 69.0 68.0 67.0 67.0 67.0 69.0 66.0 06:06:25 INFO - PROCESS | 6361 | -------- Summary: end -------- 06:06:25 INFO - PROCESS | 6361 | 06:06:26 INFO - TEST-INFO | 6361: exit 0 06:06:26 INFO - TEST-OK | kraken | took 60284ms 06:06:26 INFO - SUITE-END | took 1179s 06:06:26 INFO - Completed test suite (00:19:39) 06:06:26 INFO - javascript benchmark 06:06:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3264.396086723956, 3283.540304357389, 3291.687289707477, 3297.6537463566324, 3309.139623697838, 5696.25948626679, 5724.112762521816, 5801.390853960336, 5840.737166827487, 5877.764889404424, 4795.616350691945, 5830.7084645767645, 5880.353161152269, 5922.407759224076, 5947.910781338283, 5221.530062294392, 5246.055709972204, 5297.576193904498, 5313.734313284321, 5340.706261155635, 5357.624966252364, 5384.246205531209, 5414.052540805363, 5420.972895135519, 5460.863478413032, 5214.113600687892, 5243.4232234454175, 5271.446498117719, 5309.33633295838, 5320.361556613192, 3907.8046097695287, 4047.4535937648334, 5290.153575427951, 5304.893902121975, 5307.840764777025, 5199.53204211619, 5223.921641175385, 5230.555402790767, 5272.789088436458, 5336.599755018389, 5851.61964472308, 5926.0, 5943.257092863392, 5947.464728174442, 5992.131140984577, 6084.255793581239, 6114.2365411686305, 6135.2939121233285, 6148.415900489446, 6169.691515424211, 6191.566590338678, 6226.62640241587, 6239.407256310643, 6253.093301471306, 6270.937290627081, 5844.649321040752, 6073.42302481268, 6090.969545152335, 6093.329733729286, 6103.6948152592195, 6204.968975155095, 6283.340249273851, 6300.0, 6390.137331460193, 6396.968015159894, 6055.969720151459, 6145.66198859067, 6154.7230374633245, 6161.599496032743, 6189.381061893865, 6143.262808463013, 6225.221847269091, 6230.750769969241, 6232.407921247474, 6246.031865060922, 6136.570440069241, 6139.676133582418, 6153.646197836469, 6153.7384836108495, 6192.8761424771255, 6101.762223876893, 6162.0, 6170.290416602058, 6176.258848938066, 6179.134921111048, 5076.543111120017, 5079.87300317495, 5083.339165908408, 5086.23706444029, 5112.8721781955, 4275.657947364203, 4318.963448772272, 4458.643308535309, 4515.571020753023, 4562.041971186088, 5936.5250779937505, 5943.940560594338, 5945.346011938684, 5945.881082378328, 5973.37279585646, 6131.774283661862, 6137.714085983886, 6182.721777519932, 6185.721642526007, 6256.7810126645345, 5900.911486327708, 5947.256592925884, 5951.642901425928, 5994.298711747274, 6035.185249991283, 4683.8360657376825, 5571.582131340166, 5573.637713548606, 5575.916361254584, 5586.944130558723, 6173.166622505994, 6259.18630578031, 6303.023031430135, 6317.399847014526, 6418.775342862978, 6251.249850017845, 6252.249730032425, 6257.43683068526, 6268.153799237045, 6275.3724627536885, 6292.811215663537, 6348.460380867493, 6351.968240158954, 6360.936390636034, 6396.136521569715, 6137.662428566293, 6150.200473938359, 6173.506119510339, 6174.413430724074, 6237.157983672146, 6124.601900876429, 6261.092141639346, 6262.968685156545, 6282.120503129657, 6297.433231009231, 6216.285127210248, 6219.502439804895, 6245.5628106032145, 6256.906146407717, 6262.4990000800735, 6027.156198132177, 6112.442689633966, 6151.907721384094, 6171.814845554641, 6225.3774622537385, 5126.769295381791, 5684.431556844283, 5768.365479797135, 6129.632222066691, 6135.815925522242, 6210.254769427697, 6236.251649802053, 6267.122602835516, 6272.8431789203805, 6281.96859015702, 5906.379830117776, 6012.969935150296, 6046.879062418815, 6059.21230240066, 6095.817125486243], "value": 5737.926908976704, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.5592934147291, 520.6953931949812, 529.1533546325878, 539.4201233673801, 543.3398420918583, 30840.38319233614, 43662.0, 44688.32967505486, 44847.103057938824, 45049.7747511262, 44107.77946110265, 44483.66549003527, 44515.0, 44614.55385446145, 44703.77648111763, 8838.29293656509, 9880.308378413514, 10038.598456061749, 10062.849057264148, 10079.496025198747, 8826.293896488298, 12125.272483650964, 12398.566050188245, 12418.633950265463, 12573.811392829115, 12399.93800031003, 12438.315892625902, 12476.81284780729, 12497.375131243401, 12501.49994000239, 12317.568885089024, 12368.13423060386, 12382.75234495314, 12383.690407739788, 12387.256764594063, 12164.14850960433, 12224.205426647239, 12238.632841014694, 12238.959688426494, 12244.326562039085, 41301.96745081367, 41739.7913010436, 41758.0, 41784.58215417837, 41793.582064179274, 3385.0, 3418.7094097001786, 3425.143714071482, 3543.450765131409, 3872.554656214515, 31705.890293839606, 32732.672673273202, 32864.67135328664, 32917.341653167045, 33043.6695633043, 3237.093613788143, 3364.6971772540587, 3415.846286917095, 3448.4654878493875, 3449.1032331593715, 421318.0, 423754.0, 424630.0, 425333.0, 429450.8527457374, 60718.08922866161, 61227.69386153041, 61324.69337653283, 61337.0, 61366.69316653388, 59953.700231498566, 64324.678376608754, 64572.677136614955, 64706.0, 64843.0, 16307.91846040786, 16701.749473757904, 16709.331626734795, 16731.330746770236, 16746.83253167453, 11668.124890633237, 11833.940830295793, 12762.234265944073, 12858.485660573659, 12944.546940857213, 17294.048827314367, 17544.385946491813, 17548.947063175994, 17602.735958960628, 17869.195886185153, 5822.621529600562, 6741.224759152662, 6759.5606285591275, 6773.830654233683, 6789.694463749143, 883.439016224702, 960.6301573893961, 987.8419452887504, 998.6854140945579, 1038.2887721910365, 31437.056888293388, 31491.37017259642, 31839.84080079631, 31965.200869977973, 32661.346773064404, 1009.248110157937, 1036.3419228789774, 1041.9788607165021, 1077.256692881909, 1108.3737688206138, 14829.406823727146, 15730.270205927938, 15898.284577194054, 16071.276792544364, 16077.517674469784, 582277.0, 594761.0, 596481.0, 597801.0, 600074.0, 59524.70237648784, 59642.0, 59672.403275967554, 59698.0, 59775.701121494116, 1243.5150291386367, 1296.2287438973794, 1296.7082406458471, 1396.5321617258332, 1422.5945605502382, 25549.74450255548, 27674.308142296602, 31443.52834707527, 31873.521897172024, 33090.66909330876, 57706.7114664424, 58435.415645843, 58843.41156588551, 58900.11649825342, 58913.41086589251, 556540.0, 559299.0, 560477.0, 562806.0, 563795.0, 26450.206493805217, 26650.200493985212, 26843.731562684123, 27526.724732752416, 27617.723822761516, 49101.50898490969, 49158.0, 49949.0, 50411.74794126006, 50551.0, 38849.6115038846, 40157.0, 40214.79892600518, 41806.79096604497, 42107.36838947357, 39238.0, 39745.20509589734, 39917.600823991386, 41116.0, 41330.0, 38409.615903840604, 38952.80523597364, 39512.60487395204, 39595.01012474711, 39746.40380394238, 32791.67208327886, 34771.9568412948, 35548.28903421865, 36008.099797505274, 36035.27929441449, 25524.23427297184, 25876.094336698126, 25936.74063259419, 26445.33886652772, 26553.33616659599], "value": 20745.4655214663, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.8164228032302, 350.9982450087749, 374.6366024955794, 383.20676200265456, 400.9097952960584, 15950.28223729932, 17294.82705172948, 20800.687989680166, 21553.892230538866, 21681.457963550918, 19990.50023749407, 20500.384988450332, 21027.789722102774, 21073.57852842942, 21078.262260820873, 5426.294581704373, 5516.393196748354, 5594.944050559503, 5717.371089180187, 5722.256106706122, 3941.527016757993, 5516.531094856942, 5610.0, 5617.494425501704, 5624.493795558399, 5964.612300200495, 6229.37706229378, 6278.717457714391, 6292.150559674431, 6313.558050936436, 6011.30869949957, 6142.293636231846, 6200.534959877982, 6262.12330273762, 6286.119943207955, 6017.127516510125, 6045.546584006217, 6054.182685337511, 6059.0608455689435, 6087.878242435171, 13228.007899407583, 13533.458661653522, 13567.59297221075, 13646.86353136466, 13841.031127821057, 2234.4637287051155, 2238.82089432845, 2320.5474932388192, 2387.283814855554, 2445.8532488050773, 9642.855357169648, 9976.850347244797, 10251.538680759384, 10359.378437293712, 10462.947685261524, 1801.864860135495, 1842.649896519657, 1865.9720104198448, 1929.9324523641606, 1971.073595410155, 112772.43613782043, 117723.41138294253, 123609.0, 123756.76243237543, 124757.0, 11190.104791616814, 11328.660140195807, 11573.479193436315, 11636.709082273012, 11920.88079119215, 14715.705685886436, 14730.926345368205, 14784.852151478563, 14791.408343666348, 14795.334209960361, 8344.874826877602, 8593.0, 8598.613062412207, 8739.694110706221, 8765.649374025095, 8555.743327700178, 8559.101294364127, 8582.399232053696, 8755.124487551195, 8772.38593298463, 3906.3163946309282, 4447.933281000787, 4549.499555048942, 4573.428321459817, 4696.225122854691, 1856.981430185708, 1904.961900761977, 1916.6741653918866, 1916.7220752990738, 1917.8945158016443, 696.2524361396638, 751.4777229825219, 818.417891552154, 865.7706057398383, 913.2465715784505, 20985.580288394147, 21334.893325533274, 21466.570668586854, 21474.677879831816, 21523.892380537996, 923.2890674180843, 940.3323640215524, 975.8341082016075, 980.8675828763169, 1057.8254587992894, 9430.331598025106, 10391.48042597867, 11004.0, 11324.263922844988, 11360.318380897173, 547135.0, 559932.0, 562709.0, 572302.0, 576611.0, 134809.0, 136117.3194134023, 139007.0, 140806.0, 143314.0, 961.0677642686583, 973.600823662295, 974.88301403832, 995.7411073121041, 995.7510622344414, 17972.19125139398, 18271.451856444328, 18405.355812546495, 18410.63178736391, 18438.0, 45883.770581146884, 49421.50578494169, 49611.503884960686, 49757.0, 51294.48705512897, 69857.60284794174, 71966.64016679882, 72797.0, 74948.0, 75464.62267688627, 8104.5137291762685, 8154.918450815416, 8178.877316840371, 8365.539895305574, 8418.242358187792, 12049.939750301543, 13081.607551773463, 13109.60671179866, 13466.19202847793, 13568.321583920568, 10932.961368669963, 11049.834252486058, 11145.331280123219, 11438.428078596204, 11529.654110376701, 11191.888081119085, 11361.0, 11603.013873385587, 11681.415929203677, 11711.88737069977, 11215.775684486101, 11385.324448754702, 11450.713732156762, 11595.710107247049, 11636.418179090842, 6791.966040169965, 7712.8457430852195, 8300.2114799094, 8390.958045209734, 8595.484270943763, 10757.031867131996, 11430.714232144262, 11585.768284634092, 11609.071274297867, 11638.243514171552], "value": 9390.898073998651, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1508.705802368538, 1543.1512668032578, 1729.7664815249937, 1874.9062546872653, 1889.971650425243, 8334.083250842403, 8875.467471951688, 8884.02275749667, 8890.022097569263, 8892.777680557982, 7676.501027433213, 7888.881666775003, 8331.291840193591, 8541.701040463586, 8582.48505089695, 2331.895064722087, 2388.1283331584023, 2391.1152873436854, 2397.820163487737, 2400.9039638414442, 2352.96470552941, 2380.702412198475, 2389.438481956739, 2400.303911865557, 2403.2790162951046, 2383.5709572276983, 2387.7254115776645, 2401.423658321999, 2402.4714562796157, 2408.1691816323428, 2053.507158282009, 2360.3627020704484, 2377.95244095118, 2380.726216485109, 2389.319044072431, 2338.3686404670643, 2367.846090004144, 2368.3131891751373, 2371.253055287596, 2376.3346263046374, 8713.520756358397, 8786.912130878673, 8827.823443531093, 8837.867431988525, 8839.29285657146, 5213.139831927728, 5367.275417818583, 5376.677399356051, 5389.9191512127345, 5393.514583687448, 3609.458581212813, 3628.0, 3683.557973043252, 3688.133288677159, 3692.778433293984, 1091.5524634899652, 1092.055372103133, 1093.6609651008214, 1115.8326251062406, 1133.0482394788417, 11450.484728187252, 11544.0, 11573.826392604033, 11587.768244635061, 11611.767764644745, 4500.212462818994, 4693.084848454587, 4766.618670506351, 4796.232602783538, 4802.543758343022, 9916.107550320507, 9918.206543476648, 9918.752031199134, 9927.30508864387, 9931.404115753077, 5351.732413379315, 5362.168863826113, 5365.382980957162, 5365.382980957162, 5379.1393377059485, 4428.7121337112985, 4562.68061235717, 4594.609458196091, 4657.510961349009, 4932.482089380564, 3487.459443786217, 3887.814216663888, 3911.7848518331766, 3975.7614543127506, 4009.759414435085, 1136.8976792088752, 1275.2667216350635, 1276.9425375857923, 1287.3949243855373, 1291.7739395605731, 729.4893574497767, 783.1972228465868, 793.8568461415543, 903.6882275615034, 926.3978414030934, 5165.870853228625, 5841.182234487175, 5872.0898260769645, 5950.8809823803285, 6011.248593925759, 813.3168138763467, 886.5212785096105, 949.4635530925005, 965.1699538396973, 969.9757506062405, 3764.3036038333, 3899.883003509899, 3973.4238535412205, 4040.8181631826637, 4048.473698419187, 8873.866891996626, 10786.514606842711, 10789.298695584763, 10798.028177464066, 10807.621733239455, 10529.105026072717, 10603.787924241473, 10667.146628269875, 10777.299475534066, 10782.0, 8037.598120093972, 8093.0, 8149.225823546753, 8150.103488616248, 8177.632006559719, 5677.460641239076, 5711.828645140652, 5773.884522309614, 5829.125631155361, 5881.2942446906645, 9862.21102311799, 9927.503624818875, 9973.850392244267, 9989.900100998897, 10002.949985250027, 9643.132118109404, 10096.798064038825, 10116.241281904035, 10239.976002399999, 10285.794284114425, 6887.5178737487895, 7160.892586611101, 7166.928330716626, 7462.216467270859, 7657.6554055066545, 7824.804379890548, 7894.171112033384, 7993.160718124514, 8242.887210226543, 8353.164683531599, 7370.152432470334, 7484.251574842472, 7569.697212111454, 7601.163871974189, 7744.49660772048, 7283.417326613964, 7382.187959324363, 7438.218987006287, 7466.440016998638, 7574.242575742382, 6783.559068660521, 6936.132983377078, 6947.756828510978, 6948.583085014915, 6951.617661028692, 4854.388271383712, 4981.676191047571, 4986.351774269322, 4997.475265097112, 5036.143855544639, 4808.2787581863, 4833.202521583899, 4951.777170027285, 5082.695038297714, 5090.745462726923], "value": 4570.411526096307, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.6043719011978, 289.9188227296358, 312.9030000699783, 314.85988735012927, 316.90492852144365, 4715.386999690039, 5069.200433845982, 5192.71440070796, 5379.677219366836, 5389.541888939445, 3959.9208015839736, 5052.722100284483, 5198.194279886614, 5269.8155564555245, 5273.947260527393, 3440.6043305019866, 3609.4405367168, 4117.958820411795, 4130.047016383285, 4169.874903752892, 3514.947275790865, 3726.360401423379, 4036.7577945323374, 4134.7519148851015, 4142.130152667944, 3608.783472991629, 3890.396988466792, 3932.8820135395986, 4084.4077608746716, 4085.20338533986, 3561.8397172127316, 3714.23858109087, 3928.273269445162, 4106.28140075487, 4132.586741325874, 3205.5031470122167, 3380.7633465657414, 3549.0, 3558.430651095812, 3585.2969839326374, 4426.911461770779, 4837.467878533358, 4957.305977163164, 5170.302009228743, 5197.428282888879, 4594.264917613199, 4609.631229501631, 4627.5603817637275, 4628.861134165981, 4631.814727410933, 5304.1732023858485, 5442.863928401822, 5629.943700563023, 5694.829155125353, 5721.828345149652, 3248.668046101111, 3742.4760533525327, 3826.330392181385, 3830.751001184914, 3848.9615103849164, 4963.510946715971, 5234.555062819665, 5282.735863206824, 5703.201551782754, 5833.0, 3764.849406023783, 3844.519435070616, 3845.7115716321387, 3850.306944749916, 3850.845966161322, 3293.4685371302457, 3888.591697871739, 3897.8635747748694, 3921.8039098044983, 3940.0937784309767, 4146.647534959562, 4395.857077159962, 4531.456225252925, 4617.191991401492, 4684.367610372556, 5319.441458646864, 5630.4369563043365, 5637.605367624227, 5669.866026794658, 5729.88540229193, 1141.3551343490901, 1199.3883119609063, 1296.8962483001337, 1325.4764368074557, 1326.7678156322604, 759.8708219602681, 798.5448294472211, 811.4360519439051, 817.235884448043, 817.8282560662209, 3801.0497375656087, 4332.935005974913, 4407.187782568056, 4443.178012067778, 4485.237509623372, 4679.812807487662, 4741.407324084489, 4760.523947605281, 4775.976120119377, 4784.760761961888, 3678.7191940996754, 4105.378375541704, 4253.787310634518, 4347.2610955618, 4355.911022244439, 2364.4325361913016, 2440.511897620483, 2492.513959777826, 2509.623556466508, 2525.7979361651023, 4084.0, 4471.43913208532, 4559.658025648023, 4838.653253172209, 4905.332187056418, 4202.978985105055, 4350.71720338177, 4715.976420117878, 4935.0, 4991.425986011583, 4314.556007312473, 4335.544767799399, 4607.539246075433, 4888.02239552091, 4934.506549345037, 4133.359329303963, 4736.447664251811, 4751.572358487752, 4754.263089221176, 4796.0, 4391.407160033355, 4605.907881842278, 4719.253876065877, 4770.379850619397, 4781.497942716104, 4163.97918010408, 4859.416869975626, 5520.613557050968, 6064.696765161636, 6122.969385153224, 3916.706247031427, 3927.6661483773055, 3928.8428462862307, 3930.9803450983704, 3936.8386326033315, 5792.623479473821, 5821.417858214314, 5838.970805145947, 5880.647451086189, 5881.5588830837005, 4638.397008388888, 4906.9018619626695, 5349.144136938071, 5757.481826635623, 5779.156860687595, 5740.483356497935, 5750.894516960794, 5758.8848223034465, 5759.308882934075, 5766.8846623066465, 5011.173156429149, 5638.830835074954, 5644.576656750843, 5649.717514124195, 5675.716214189357, 5176.482351764794, 5674.517665998436, 5769.249997500298, 5784.0, 5795.0, 5141.742912854417, 5365.59758018158, 5625.521830644441, 5654.6428857074825, 5717.197554054614], "value": 3883.1263230123996, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.3279070115937, 728.9161746399166, 745.2808040241166, 760.9429292803035, 808.4179390838598, 15608.765868511968, 17056.829431705693, 17671.381501647415, 17702.645947081066, 17702.822971770278, 16538.586535336624, 16609.252583633734, 16612.335506579722, 16624.66750664986, 16643.0, 581.9272590926134, 841.7600983719641, 853.5817449449787, 855.7561095087901, 859.6647307550061, 699.1749735312329, 839.5046922315832, 852.4586887326531, 864.148813418782, 877.5963056993759, 908.0329449136682, 909.4270609515995, 909.749818799832, 911.6262332443699, 911.731039343392, 902.6479672927563, 906.9977674669461, 907.2333877873186, 907.3059109781022, 908.4776253654176, 523.6465385864526, 534.636447215893, 535.1517844216921, 539.8812261302508, 542.7449098923499, 15113.924430377778, 15591.922040389838, 15634.140122293269, 15673.68652626941, 15700.900936934418, 14109.929450352682, 14460.638484037878, 14627.853721462861, 14695.70608587833, 14739.778903316459, 11811.704707382298, 11895.821562676567, 12001.699957501045, 12020.879791202064, 12416.937915310456, 3848.903777405559, 3917.353636649978, 3926.725129240954, 3963.2667956427863, 3970.404439334123, 112588.0, 115977.0, 118558.0, 118602.0, 121787.99999999911, 11186.216964812547, 11327.309938601373, 11453.828192577119, 11532.827007594893, 11804.822927656092, 21990.81133235704, 22300.88849555774, 22325.55348893013, 22350.552988940457, 22369.440763980707, 8099.190080991972, 8171.42800003994, 8183.626748242943, 8529.0, 8539.359548033923, 3842.481265029241, 4410.448693913261, 4429.546270800907, 4560.84037058708, 4774.689645173053, 3649.087728067983, 3815.5802861685193, 3820.6561409472724, 3823.2544653792243, 3825.101101241206, 567.6962824888665, 682.9453643708491, 686.629220221085, 690.2579726793717, 694.1184695436868, 508.98473045808686, 597.086338285793, 661.5534514202894, 679.3104998426601, 698.7798843364324, 9343.972163062057, 9430.858537121949, 9569.61721531145, 9632.0, 9688.806223875483, 529.5525281137433, 529.9920501192486, 604.3261763134074, 636.2587585462976, 674.6458109492555, 4525.343825145402, 4648.5063693476295, 4655.74393408359, 4665.136949664323, 4807.4471435784635, 89497.10502895017, 112325.15122731493, 118952.40523797326, 135967.0, 140183.0, 118733.0, 125146.0, 126513.73486265019, 127905.72094279125, 132683.33658331647, 93394.0, 100074.0, 100099.99800004104, 106706.0, 108994.91005090006, 9406.740765869048, 9681.180797097704, 9761.581198289494, 9795.461249631338, 9799.125416824505, 56829.431705682415, 59807.80384392375, 60870.086948694916, 60984.69507652433, 61773.07340389808, 84783.15216848, 84860.57569712112, 86713.56643216744, 88519.55740221516, 89151.0, 12645.0, 13192.0, 13341.265040666445, 13478.595642130751, 13575.587933921317, 14573.708525829636, 14884.553463396116, 14901.92549037248, 15026.398944042116, 15579.532614021597, 11829.82255266189, 13764.31178441054, 14088.436462541793, 14137.434502620192, 14277.928610356881, 12722.554710585086, 13188.472461101448, 13385.66535836612, 13508.594742157751, 13782.448702051806, 13276.734465310832, 13687.657808554866, 13726.519571814939, 13858.369632856255, 13952.232627205602, 11231.887681123084, 11239.887601124212, 11536.711582210512, 11557.608467931057, 11622.302661840316, 8182.713605023796, 10653.201009924442, 11473.770524589294, 11609.245399049034, 11668.474918628512], "value": 7820.44725319572, "unit": "score"}], "name": "dromaeo_css", "value": 7330.505445026846, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 122.0, 164.0, 149.0, 113.0, 107.0, 111.0, 111.0, 112.0], "value": 120.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 106.0, 107.0, 168.0, 105.0, 109.0, 130.0, 106.0, 144.0, 108.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 202.0, 258.0, 188.0, 184.0, 184.0, 183.0, 184.0, 182.0], "value": 193.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 63.0, 88.0, 59.0, 58.0, 66.0, 66.0, 83.0, 58.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 136.0, 99.0, 95.0, 71.0, 98.0, 71.0, 67.0, 102.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 89.0, 85.0, 87.0, 84.0, 89.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 146.0, 115.0, 93.0, 94.0, 93.0, 99.0, 120.0, 93.0, 93.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 84.0, 83.0, 83.0, 85.0, 84.0, 84.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 57.0, 54.0, 55.0, 56.0, 56.0, 55.0, 55.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 78.0, 77.0, 74.0, 70.0, 70.0, 74.0, 74.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 84.0, 85.0, 87.0, 84.0, 84.0, 84.0, 88.0, 86.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 135.0, 147.0, 147.0, 144.0, 141.0, 134.0, 143.0, 233.0, 154.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 172.0, 173.0, 173.0, 172.0, 170.0, 177.0, 171.0, 171.0, 178.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 69.0, 68.0, 67.0, 67.0, 67.0, 69.0, 66.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1475.7, "alertThreshold": 2.0}]} 06:06:26 INFO - Return code: 0 06:06:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:06:26 INFO - # TBPL SUCCESS # 06:06:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:06:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:06:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:06:26 INFO - 2016-08-18 06:06:26.887 screenresolution[6365:109334] starting screenresolution argv=screenresolution get 06:06:26 INFO - 2016-08-18 06:06:26.890 screenresolution[6365:109334] Display 0: 1600x1200x32@60 06:06:26 INFO - 2016-08-18 06:06:26.896 screenresolution[6366:109337] starting screenresolution argv=screenresolution list 06:06:26 INFO - Available Modes on Display 0 06:06:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:06:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:06:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:06:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:06:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:06:27 INFO - Intel Iris: 06:06:27 INFO - Chipset Model: Intel Iris 06:06:27 INFO - Type: GPU 06:06:27 INFO - Bus: Built-In 06:06:27 INFO - VRAM (Dynamic, Max): 1536 MB 06:06:27 INFO - Vendor: Intel (0x8086) 06:06:27 INFO - Device ID: 0x0a2e 06:06:27 INFO - Revision ID: 0x0009 06:06:27 INFO - Displays: 06:06:27 INFO - Display: 06:06:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:06:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:06:27 INFO - Display Serial Number: 1600x1200 60 06:06:27 INFO - Main Display: Yes 06:06:27 INFO - Mirror: Off 06:06:27 INFO - Online: Yes 06:06:27 INFO - Rotation: Supported 06:06:27 INFO - Return code: 0 06:06:27 INFO - Running post-action listener: _resource_record_post_action 06:06:27 INFO - [mozharness: 2016-08-18 13:06:27.133150Z] Finished run-tests step (success) 06:06:27 INFO - Running post-run listener: _resource_record_post_run 06:06:27 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 1518592; Write bytes: 704916480; Read time: 21; Write time: 1954 06:06:27 INFO - TinderboxPrint: CPU usage
53.7% 06:06:27 INFO - TinderboxPrint: I/O read bytes / time
1,518,592 / 21 06:06:27 INFO - TinderboxPrint: I/O write bytes / time
704,916,480 / 1,954 06:06:27 INFO - TinderboxPrint: CPU idle
2,249.7 (46.3%) 06:06:27 INFO - TinderboxPrint: CPU system
974.1 (20.0%) 06:06:27 INFO - TinderboxPrint: CPU user
1,637.8 (33.7%) 06:06:27 INFO - TinderboxPrint: Swap in / out
408,240,128 / 0 06:06:27 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388078080; Write bytes: 383010816; Read time: 29399; Write time: 777 06:06:27 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 1518592; Write bytes: 296436736; Read time: 21; Write time: 1116 06:06:27 INFO - Running post-run listener: _upload_blobber_files 06:06:27 INFO - Blob upload gear active. 06:06:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:06:27 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:06:27 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:06:27 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:06:28 INFO - (blobuploader) - INFO - Open directory for files ... 06:06:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:06:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:06:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:29 INFO - (blobuploader) - INFO - Done attempting. 06:06:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:06:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:06:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:30 INFO - (blobuploader) - INFO - Done attempting. 06:06:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:06:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:06:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:06:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:06:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:06:32 INFO - (blobuploader) - INFO - Done attempting. 06:06:32 INFO - (blobuploader) - INFO - Iteration through files over. 06:06:32 INFO - Return code: 0 06:06:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:06:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:06:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dc0c1797854f9cea16110a37800481b06301d0478bebfc695c1e7246841c46b2a992e786efaa636a9779cd94a7ec056b57f8e6edf6bfac37db0d9f4d03f6308c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8d13f2272fc40e406199dee1bffadbfc6ca37997f861db1b7beab727106da567803898eb24ea6d1238b8f4d1e75399eb9530fb9f8bf6a65a5346cff036fe8ead", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dbca94dd647a4596483cb759c6674d9c8f686ddfa8985c97496e83851f3f6008cd6f488de60baa493b9f855d8096940c15206aaed501386263c661cf3abc5df6"} 06:06:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:06:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:06:32 INFO - Contents: 06:06:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dc0c1797854f9cea16110a37800481b06301d0478bebfc695c1e7246841c46b2a992e786efaa636a9779cd94a7ec056b57f8e6edf6bfac37db0d9f4d03f6308c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8d13f2272fc40e406199dee1bffadbfc6ca37997f861db1b7beab727106da567803898eb24ea6d1238b8f4d1e75399eb9530fb9f8bf6a65a5346cff036fe8ead", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dbca94dd647a4596483cb759c6674d9c8f686ddfa8985c97496e83851f3f6008cd6f488de60baa493b9f855d8096940c15206aaed501386263c661cf3abc5df6"} 06:06:32 INFO - Running post-run listener: copy_logs_to_upload_dir 06:06:32 INFO - Copying logs to upload dir... 06:06:32 INFO - mkdir: /builds/slave/test/build/upload/logs 06:06:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.606146 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-18 06:06:32.653684) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:06:32.657744) ========= 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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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/dc0c1797854f9cea16110a37800481b06301d0478bebfc695c1e7246841c46b2a992e786efaa636a9779cd94a7ec056b57f8e6edf6bfac37db0d9f4d03f6308c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8d13f2272fc40e406199dee1bffadbfc6ca37997f861db1b7beab727106da567803898eb24ea6d1238b8f4d1e75399eb9530fb9f8bf6a65a5346cff036fe8ead", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dbca94dd647a4596483cb759c6674d9c8f686ddfa8985c97496e83851f3f6008cd6f488de60baa493b9f855d8096940c15206aaed501386263c661cf3abc5df6"} build_url:https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011288 build_url: 'https://queue.taskcluster.net/v1/task/b_qnz5KWSbKArK0N9HeAwA/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/dc0c1797854f9cea16110a37800481b06301d0478bebfc695c1e7246841c46b2a992e786efaa636a9779cd94a7ec056b57f8e6edf6bfac37db0d9f4d03f6308c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8d13f2272fc40e406199dee1bffadbfc6ca37997f861db1b7beab727106da567803898eb24ea6d1238b8f4d1e75399eb9530fb9f8bf6a65a5346cff036fe8ead", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dbca94dd647a4596483cb759c6674d9c8f686ddfa8985c97496e83851f3f6008cd6f488de60baa493b9f855d8096940c15206aaed501386263c661cf3abc5df6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:06:32.691101) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:06:32.691477) ========= 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.Qah73gBkJJ/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.oaaFwB0GFZ/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.005236 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:06:32.740096) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:06:32.740461) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:06:32.740796) ========= ========= Total master_lag: 0.18 =========