builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0322 starttime: 1472647895.93 results: success (0) buildid: 20160831044320 builduid: f01ff747276e482d9c9b958a32f29231 revision: a328778db08dbbe7111f0c7948a05b0bca719373 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:35.926304) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:35.926746) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:35.927104) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:35.985152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:35.985644) ========= 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.N4Crg1DRPL/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.QE6foDKI1o/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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-31 05:51:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.16M=0.001s 2016-08-31 05:51:36 (8.16 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.592374 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:36.601994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:36.602270) ========= 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.N4Crg1DRPL/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.QE6foDKI1o/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.021427 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:36.650085) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 05:51:36.650404) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a328778db08dbbe7111f0c7948a05b0bca719373 --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 a328778db08dbbe7111f0c7948a05b0bca719373 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N4Crg1DRPL/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.QE6foDKI1o/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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-31 05:51:36,736 truncating revision to first 12 chars 2016-08-31 05:51:36,736 Setting DEBUG logging. 2016-08-31 05:51:36,736 attempt 1/10 2016-08-31 05:51:36,736 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a328778db08d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 05:51:37,917 unpacking tar archive at: autoland-a328778db08d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.458150 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 05:51:38.136192) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:38.136704) ========= script_repo_revision: a328778db08dbbe7111f0c7948a05b0bca719373 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:38.137303) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:38.137714) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 05:51:38.156225) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-31 05:51:38.156494) ========= /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.N4Crg1DRPL/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.QE6foDKI1o/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:51:38 INFO - MultiFileLogger online at 20160831 05:51:38 in /builds/slave/test 05:51:38 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:51:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:51:38 INFO - {'append_to_log': False, 05:51:38 INFO - 'base_work_dir': '/builds/slave/test', 05:51:38 INFO - 'blob_upload_branch': 'Autoland', 05:51:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:51:38 INFO - 'branch': 'Autoland', 05:51:38 INFO - 'buildbot_json_path': 'buildprops.json', 05:51:38 INFO - 'config_files': ('talos/mac_config.py',), 05:51:38 INFO - 'default_actions': ('clobber', 05:51:38 INFO - 'read-buildbot-config', 05:51:38 INFO - 'download-and-extract', 05:51:38 INFO - 'populate-webroot', 05:51:38 INFO - 'create-virtualenv', 05:51:38 INFO - 'install', 05:51:38 INFO - 'run-tests'), 05:51:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:51:38 INFO - 'download_minidump_stackwalk': True, 05:51:38 INFO - 'download_symbols': 'ondemand', 05:51:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:51:38 INFO - 'tooltool.py': '/tools/tooltool.py', 05:51:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:51:38 INFO - '/tools/misc-python/virtualenv.py')}, 05:51:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:51:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:51:38 INFO - 'installer_path': 'installer.exe', 05:51:38 INFO - 'log_level': 'info', 05:51:38 INFO - 'log_name': 'talos', 05:51:38 INFO - 'log_to_console': True, 05:51:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:51:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:51:38 INFO - 'opt_config_files': (), 05:51:38 INFO - 'pip_index': False, 05:51:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:51:38 INFO - 'cmd': ('bash', 05:51:38 INFO - '-c', 05:51:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:51:38 INFO - 'enabled': True, 05:51:38 INFO - 'halt_on_failure': False, 05:51:38 INFO - 'name': 'check_screen_resolution'},), 05:51:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:51:38 INFO - 'cmd': ('bash', 05:51:38 INFO - '-c', 05:51:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:51:38 INFO - 'enabled': True, 05:51:38 INFO - 'halt_on_failure': False, 05:51:38 INFO - 'name': 'check_screen_resolution'},), 05:51:38 INFO - 'run_cmd_checks_enabled': True, 05:51:38 INFO - 'sps_profile': False, 05:51:38 INFO - 'sps_profile_interval': 0, 05:51:38 INFO - 'suite': 'dromaeojs', 05:51:38 INFO - 'system_bits': '32', 05:51:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:51:38 INFO - 'title': 't-yosemite-r7-0322', 05:51:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:51:38 INFO - 'use_talos_json': True, 05:51:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:51:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:51:38 INFO - 'work_dir': 'build'} 05:51:38 INFO - [mozharness: 2016-08-31 12:51:38.363262Z] Running clobber step. 05:51:38 INFO - Running pre-action listener: _resource_record_pre_action 05:51:38 INFO - Running main action method: clobber 05:51:38 INFO - rmtree: /builds/slave/test/build 05:51:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:51:40 INFO - Running post-action listener: _resource_record_post_action 05:51:40 INFO - [mozharness: 2016-08-31 12:51:40.650939Z] Finished clobber step (success) 05:51:40 INFO - [mozharness: 2016-08-31 12:51:40.651071Z] Running read-buildbot-config step. 05:51:40 INFO - Running pre-action listener: _resource_record_pre_action 05:51:40 INFO - Running main action method: read_buildbot_config 05:51:40 INFO - Using buildbot properties: 05:51:40 INFO - { 05:51:40 INFO - "project": "", 05:51:40 INFO - "product": "firefox", 05:51:40 INFO - "script_repo_revision": "production", 05:51:40 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 05:51:40 INFO - "repository": "", 05:51:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 05:51:40 INFO - "buildid": "20160831044320", 05:51:40 INFO - "pgo_build": "False", 05:51:40 INFO - "basedir": "/builds/slave/test", 05:51:40 INFO - "buildnumber": 135, 05:51:40 INFO - "slavename": "t-yosemite-r7-0322", 05:51:40 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:51:40 INFO - "platform": "yosemite_r7", 05:51:40 INFO - "scheduler": "tests-autoland-macosx64-talos", 05:51:40 INFO - "branch": "autoland", 05:51:40 INFO - "repo_path": "integration/autoland", 05:51:40 INFO - "revision": "a328778db08dbbe7111f0c7948a05b0bca719373", 05:51:40 INFO - "stage_platform": "macosx64", 05:51:40 INFO - "builduid": "f01ff747276e482d9c9b958a32f29231", 05:51:40 INFO - "slavebuilddir": "test" 05:51:40 INFO - } 05:51:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:51:40 INFO - Running post-action listener: _resource_record_post_action 05:51:40 INFO - [mozharness: 2016-08-31 12:51:40.654558Z] Finished read-buildbot-config step (success) 05:51:40 INFO - [mozharness: 2016-08-31 12:51:40.654664Z] Running download-and-extract step. 05:51:40 INFO - Running pre-action listener: _resource_record_pre_action 05:51:40 INFO - Running main action method: download_and_extract 05:51:40 INFO - mkdir: /builds/slave/test/build/tests 05:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:40 INFO - trying https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:51:40 INFO - Downloading https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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:51:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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:51:41 INFO - Downloaded 1199 bytes. 05:51:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:51:41 INFO - Using the following test package requirements: 05:51:41 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:51:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:51:41 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:51:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:51:41 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:51:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:51:41 INFO - u'jsshell-mac.zip'], 05:51:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:51:41 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:51:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:51:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:51:41 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:51:41 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:51:41 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:51:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:51:41 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:51:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:51:41 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:51:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:41 INFO - trying https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:51:41 INFO - Downloading https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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:51:42 INFO - Downloaded 16390552 bytes. 05:51:42 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:51:49 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:51: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:51:49 INFO - trying https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:51:49 INFO - Downloading https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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:51:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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:51:50 INFO - Downloaded 16390552 bytes. 05:51:50 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:51: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:51:56 INFO - trying https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:51:56 INFO - Downloading https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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:51:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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:51:58 INFO - Downloaded 14413998 bytes. 05:51:58 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:51:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:51:58 INFO - trying https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:51:58 INFO - Downloading https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:51:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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:52:01 INFO - Downloaded 111559032 bytes. 05:52:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:52:01 INFO - mkdir: /builds/slave/test/properties 05:52:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:52:01 INFO - Writing to file /builds/slave/test/properties/build_url 05:52:01 INFO - Contents: 05:52:01 INFO - build_url:https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:52:01 INFO - Running post-action listener: _resource_record_post_action 05:52:01 INFO - Running post-action listener: set_extra_try_arguments 05:52:01 INFO - [mozharness: 2016-08-31 12:52:01.835005Z] Finished download-and-extract step (success) 05:52:01 INFO - [mozharness: 2016-08-31 12:52:01.835153Z] Running populate-webroot step. 05:52:01 INFO - Running pre-action listener: _resource_record_pre_action 05:52:01 INFO - Running main action method: populate_webroot 05:52:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:52:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:52:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:52:01 INFO - '--tppagecycles', 05:52:01 INFO - '7'], 05:52:01 INFO - 'tests': ['tsvgm']}}, 05:52:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:52:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:52:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:52:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:52:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:01 INFO - 'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:52:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:52:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:01 INFO - 'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['damp', 'tps']}, 05:52:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:01 INFO - 'tests': ['damp', 'tps']}, 05:52:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['dromaeo_dom']}, 05:52:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:52:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['basic_compositor_video']}, 05:52:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:52:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['a11yr', 05:52:01 INFO - 'ts_paint', 05:52:01 INFO - 'tpaint', 05:52:01 INFO - 'sessionrestore', 05:52:01 INFO - 'sessionrestore_no_auto_restore', 05:52:01 INFO - 'tabpaint']}, 05:52:01 INFO - 'other-e10s': {'tests': ['a11yr', 05:52:01 INFO - 'ts_paint', 05:52:01 INFO - 'tpaint', 05:52:01 INFO - 'sessionrestore', 05:52:01 INFO - 'sessionrestore_no_auto_restore', 05:52:01 INFO - 'tabpaint']}, 05:52:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:52:01 INFO - 'ts_paint', 05:52:01 INFO - 'tpaint', 05:52:01 INFO - 'sessionrestore', 05:52:01 INFO - 'sessionrestore_no_auto_restore', 05:52:01 INFO - 'tabpaint']}, 05:52:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:52:01 INFO - 'ts_paint', 05:52:01 INFO - 'tpaint', 05:52:01 INFO - 'sessionrestore', 05:52:01 INFO - 'sessionrestore_no_auto_restore', 05:52:01 INFO - 'tabpaint']}, 05:52:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['a11yr', 05:52:01 INFO - 'ts_paint', 05:52:01 INFO - 'tpaint', 05:52:01 INFO - 'sessionrestore', 05:52:01 INFO - 'sessionrestore_no_auto_restore', 05:52:01 INFO - 'tabpaint']}, 05:52:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['a11yr', 05:52:01 INFO - 'ts_paint', 05:52:01 INFO - 'tpaint', 05:52:01 INFO - 'sessionrestore', 05:52:01 INFO - 'sessionrestore_no_auto_restore', 05:52:01 INFO - 'tabpaint']}, 05:52:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['tsvgx', 05:52:01 INFO - 'tsvgr_opacity', 05:52:01 INFO - 'tart', 05:52:01 INFO - 'tscrollx', 05:52:01 INFO - 'cart']}, 05:52:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:52:01 INFO - 'tsvgr_opacity', 05:52:01 INFO - 'tart', 05:52:01 INFO - 'tscrollx', 05:52:01 INFO - 'cart']}, 05:52:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:01 INFO - 'talos_options': ['--disable-e10s'], 05:52:01 INFO - 'tests': ['tp5o']}, 05:52:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:01 INFO - 'tests': ['tp5o']}, 05:52:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:01 INFO - 'talos_options': ['--disable-e10s', 05:52:01 INFO - '--xperf_path', 05:52:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:52:01 INFO - 'tests': ['tp5n']}, 05:52:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:01 INFO - 'talos_options': ['--xperf_path', 05:52:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:52:01 INFO - 'tests': ['tp5n']}}, 05:52:01 INFO - 'talos.zip': {'path': '', 05:52:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:52:01 INFO - Running post-action listener: _resource_record_post_action 05:52:01 INFO - [mozharness: 2016-08-31 12:52:01.853889Z] Finished populate-webroot step (success) 05:52:01 INFO - [mozharness: 2016-08-31 12:52:01.853995Z] Running create-virtualenv step. 05:52:01 INFO - Running pre-action listener: _resource_record_pre_action 05:52:01 INFO - Running main action method: create_virtualenv 05:52:01 INFO - Creating virtualenv /builds/slave/test/build/venv 05:52:01 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:52:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:52:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:52:01 INFO - Using real prefix '/tools/python27' 05:52:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:52:02 INFO - Installing distribute.............................................................................................................................................................................................done. 05:52:05 INFO - Installing pip.................done. 05:52:05 INFO - Return code: 0 05:52:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:52: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:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:52:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:05 INFO - 'HOME': '/Users/cltbld', 05:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:05 INFO - 'LOGNAME': 'cltbld', 05:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:05 INFO - 'MOZ_NO_REMOTE': '1', 05:52:05 INFO - 'NO_EM_RESTART': '1', 05:52:05 INFO - 'PAGER': '/bin/cat', 05:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:05 INFO - 'PWD': '/builds/slave/test', 05:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:05 INFO - 'SHELL': '/bin/bash', 05:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:05 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:05 INFO - 'USER': 'cltbld', 05:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:05 INFO - 'XPC_FLAGS': '0x0', 05:52:05 INFO - 'XPC_SERVICE_NAME': '0', 05:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:05 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:52:05 INFO - Cleaning up... 05:52:05 INFO - Return code: 0 05:52:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:52: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:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:52:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:05 INFO - 'HOME': '/Users/cltbld', 05:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:05 INFO - 'LOGNAME': 'cltbld', 05:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:05 INFO - 'MOZ_NO_REMOTE': '1', 05:52:05 INFO - 'NO_EM_RESTART': '1', 05:52:05 INFO - 'PAGER': '/bin/cat', 05:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:05 INFO - 'PWD': '/builds/slave/test', 05:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:05 INFO - 'SHELL': '/bin/bash', 05:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:05 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:05 INFO - 'USER': 'cltbld', 05:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:05 INFO - 'XPC_FLAGS': '0x0', 05:52:05 INFO - 'XPC_SERVICE_NAME': '0', 05:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:06 INFO - Downloading/unpacking psutil>=3.1.1 05:52:06 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:52:06 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:52:06 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:52:06 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:52:06 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:52:06 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:52:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:52:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:52:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:52:09 INFO - Installing collected packages: psutil 05:52:09 INFO - Running setup.py install for psutil 05:52:09 INFO - building 'psutil._psutil_osx' extension 05:52:09 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:52:09 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:52:09 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:52:09 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:52:09 INFO - building 'psutil._psutil_posix' extension 05:52:09 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:52:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:52:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:52:10 INFO - ^ 05:52:10 INFO - 1 warning generated. 05:52:10 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:52:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:52:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:52:10 INFO - Successfully installed psutil 05:52:10 INFO - Cleaning up... 05:52:10 INFO - Return code: 0 05:52:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:10 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 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:10 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:52:10 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:52:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:10 INFO - 'HOME': '/Users/cltbld', 05:52:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:10 INFO - 'LOGNAME': 'cltbld', 05:52:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:10 INFO - 'MOZ_NO_REMOTE': '1', 05:52:10 INFO - 'NO_EM_RESTART': '1', 05:52:10 INFO - 'PAGER': '/bin/cat', 05:52:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:10 INFO - 'PWD': '/builds/slave/test', 05:52:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:10 INFO - 'SHELL': '/bin/bash', 05:52:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:10 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:10 INFO - 'USER': 'cltbld', 05:52:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:10 INFO - 'XPC_FLAGS': '0x0', 05:52:10 INFO - 'XPC_SERVICE_NAME': '0', 05:52:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:52:10 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:52:10 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:52:10 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:52:10 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:52:10 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:52:10 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:52:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:52:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:52:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:52:13 INFO - Installing collected packages: mozsystemmonitor 05:52:13 INFO - Running setup.py install for mozsystemmonitor 05:52:13 INFO - Successfully installed mozsystemmonitor 05:52:13 INFO - Cleaning up... 05:52:13 INFO - Return code: 0 05:52:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:13 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 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:13 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:52:13 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:52:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:13 INFO - 'HOME': '/Users/cltbld', 05:52:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:13 INFO - 'LOGNAME': 'cltbld', 05:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:13 INFO - 'MOZ_NO_REMOTE': '1', 05:52:13 INFO - 'NO_EM_RESTART': '1', 05:52:13 INFO - 'PAGER': '/bin/cat', 05:52:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:13 INFO - 'PWD': '/builds/slave/test', 05:52:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:13 INFO - 'SHELL': '/bin/bash', 05:52:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:13 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:13 INFO - 'USER': 'cltbld', 05:52:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:13 INFO - 'XPC_FLAGS': '0x0', 05:52:13 INFO - 'XPC_SERVICE_NAME': '0', 05:52:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:13 INFO - Downloading/unpacking jsonschema==2.5.1 05:52:13 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:52:13 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:52:13 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:52:13 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:52:13 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:52:13 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:52:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:52:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:52:16 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:52:16 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:52:16 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:52:16 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:52:16 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:52:16 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:52:16 INFO - Downloading functools32-3.2.3-2.tar.gz 05:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:52:16 INFO - warning: no files found matching '*.txt' 05:52:16 INFO - no previously-included directories found matching 'build' 05:52:16 INFO - no previously-included directories found matching 'dist' 05:52:16 INFO - no previously-included directories found matching '.git*' 05:52:16 INFO - Installing collected packages: jsonschema, functools32 05:52:16 INFO - Running setup.py install for functools32 05:52:16 INFO - warning: no files found matching '*.txt' 05:52:16 INFO - no previously-included directories found matching 'build' 05:52:16 INFO - no previously-included directories found matching 'dist' 05:52:16 INFO - no previously-included directories found matching '.git*' 05:52:16 INFO - Successfully installed jsonschema functools32 05:52:16 INFO - Cleaning up... 05:52:16 INFO - Return code: 0 05:52:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:52:16 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:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:16 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:52:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:16 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 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:16 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:52:16 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:52:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:16 INFO - 'HOME': '/Users/cltbld', 05:52:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:16 INFO - 'LOGNAME': 'cltbld', 05:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:16 INFO - 'MOZ_NO_REMOTE': '1', 05:52:16 INFO - 'NO_EM_RESTART': '1', 05:52:16 INFO - 'PAGER': '/bin/cat', 05:52:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:16 INFO - 'PWD': '/builds/slave/test', 05:52:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:16 INFO - 'SHELL': '/bin/bash', 05:52:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:16 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:16 INFO - 'USER': 'cltbld', 05:52:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:16 INFO - 'XPC_FLAGS': '0x0', 05:52:16 INFO - 'XPC_SERVICE_NAME': '0', 05:52:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:52:17 INFO - Cleaning up... 05:52:17 INFO - Return code: 0 05:52:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:52:17 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:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:17 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:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:17 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 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:17 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:52:17 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:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:17 INFO - 'HOME': '/Users/cltbld', 05:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:17 INFO - 'LOGNAME': 'cltbld', 05:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:17 INFO - 'MOZ_NO_REMOTE': '1', 05:52:17 INFO - 'NO_EM_RESTART': '1', 05:52:17 INFO - 'PAGER': '/bin/cat', 05:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:17 INFO - 'PWD': '/builds/slave/test', 05:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:17 INFO - 'SHELL': '/bin/bash', 05:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:17 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:17 INFO - 'USER': 'cltbld', 05:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:17 INFO - 'XPC_FLAGS': '0x0', 05:52:17 INFO - 'XPC_SERVICE_NAME': '0', 05:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:17 INFO - Downloading/unpacking blobuploader==1.2.4 05:52:17 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:52:17 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:52:17 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:52:17 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:52:17 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:52:17 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:52:19 INFO - Downloading blobuploader-1.2.4.tar.gz 05:52:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:52:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:52:19 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:52:19 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:52:19 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:52:19 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:52:19 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:52:19 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:52:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:52:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:52:20 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:52:20 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:52:20 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:52:20 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:52:20 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:52:20 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:52:20 INFO - Downloading docopt-0.6.1.tar.gz 05:52:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:52:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:52:21 INFO - Running setup.py install for blobuploader 05:52:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:52:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:52:21 INFO - Running setup.py install for requests 05:52:21 INFO - Running setup.py install for docopt 05:52:21 INFO - Successfully installed blobuploader requests docopt 05:52:21 INFO - Cleaning up... 05:52:21 INFO - Return code: 0 05:52:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:52: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:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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:52:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:21 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 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:21 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:52:21 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:52:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:21 INFO - 'HOME': '/Users/cltbld', 05:52:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:21 INFO - 'LOGNAME': 'cltbld', 05:52:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:21 INFO - 'MOZ_NO_REMOTE': '1', 05:52:21 INFO - 'NO_EM_RESTART': '1', 05:52:21 INFO - 'PAGER': '/bin/cat', 05:52:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:21 INFO - 'PWD': '/builds/slave/test', 05:52:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:21 INFO - 'SHELL': '/bin/bash', 05:52:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:21 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:21 INFO - 'USER': 'cltbld', 05:52:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:21 INFO - 'XPC_FLAGS': '0x0', 05:52:21 INFO - 'XPC_SERVICE_NAME': '0', 05:52:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:52:22 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-ops9Mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:52:22 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Op4ACm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:52:22 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-k1azJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:52:22 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-L37XUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:52:22 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-ohhSsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:52:22 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-dnm7rC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:52:22 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-OZ0wxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:52:22 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-BHUj20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:52:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:52:22 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-QbMdSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:52:23 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-r4_ejt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:52:23 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Ps_D21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:52:23 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-np1_ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:52:23 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-fOuPnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:52:23 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Ls1bXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:52:23 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-p6y1pW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:52:23 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-3KqrIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:52:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:52:23 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-0KBldi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:52:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:52:23 INFO - Running setup.py install for manifestparser 05:52:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:52:24 INFO - Running setup.py install for mozcrash 05:52:24 INFO - Running setup.py install for mozdebug 05:52:24 INFO - Running setup.py install for mozdevice 05:52:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:52:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:52:24 INFO - Running setup.py install for mozfile 05:52:24 INFO - Running setup.py install for mozhttpd 05:52:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:52:24 INFO - Running setup.py install for mozinfo 05:52:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:52:24 INFO - Running setup.py install for mozInstall 05:52:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:52:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:52:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:52:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:52:25 INFO - Running setup.py install for mozleak 05:52:25 INFO - Running setup.py install for mozlog 05:52:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:52:25 INFO - Running setup.py install for moznetwork 05:52:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:52:25 INFO - Running setup.py install for mozprocess 05:52:25 INFO - Running setup.py install for mozprofile 05:52:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:52:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:52:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:52:25 INFO - Running setup.py install for mozrunner 05:52:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:52:26 INFO - Running setup.py install for mozscreenshot 05:52:26 INFO - Running setup.py install for moztest 05:52:26 INFO - Running setup.py install for mozversion 05:52:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:52:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:52:26 INFO - Cleaning up... 05:52:26 INFO - Return code: 0 05:52:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:52:26 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:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:26 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:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:26 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 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:26 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:52:26 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:52:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:26 INFO - 'HOME': '/Users/cltbld', 05:52:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:26 INFO - 'LOGNAME': 'cltbld', 05:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:26 INFO - 'MOZ_NO_REMOTE': '1', 05:52:26 INFO - 'NO_EM_RESTART': '1', 05:52:26 INFO - 'PAGER': '/bin/cat', 05:52:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:26 INFO - 'PWD': '/builds/slave/test', 05:52:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:26 INFO - 'SHELL': '/bin/bash', 05:52:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:26 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:26 INFO - 'USER': 'cltbld', 05:52:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:26 INFO - 'XPC_FLAGS': '0x0', 05:52:26 INFO - 'XPC_SERVICE_NAME': '0', 05:52:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:52:26 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-ijqSTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:52:26 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:52:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:52:26 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-sKpjQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:52:26 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:52:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:52:26 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-I8CKMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:52:27 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:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:52:27 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-D2li45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:52:27 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:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:52:27 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-Ab6Ilx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:52:27 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:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:52:27 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-qdICbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:52:27 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:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:52:27 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-vmpjZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:52:27 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:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:52:27 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-5zVP48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:52:27 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:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:52:27 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-CgEA3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:52:27 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:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:52:27 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-LU4u6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:52:27 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:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:52:27 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-2WCRYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:52:27 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:52:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:52:27 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-cU8wGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:52:28 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:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:52:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-EvtEwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:52:28 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:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:52:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-kEYhMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:52:28 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:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:52:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-3NfQnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:52:28 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:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:52:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-QisBdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:52:28 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:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:52:28 INFO - Running setup.py (path:/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/pip-pxftMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:52:28 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:52:28 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:52:28 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:52:28 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:52:28 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:52:28 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:52:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:30 INFO - Downloading blessings-1.6.tar.gz 05:52:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:52:31 INFO - Installing collected packages: blessings 05:52:31 INFO - Running setup.py install for blessings 05:52:31 INFO - Successfully installed blessings 05:52:31 INFO - Cleaning up... 05:52:31 INFO - Return code: 0 05:52:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:52:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:52:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:52:31 INFO - Reading from file tmpfile_stdout 05:52:31 INFO - Current package versions: 05:52:31 INFO - blessings == 1.6 05:52:31 INFO - blobuploader == 1.2.4 05:52:31 INFO - docopt == 0.6.1 05:52:31 INFO - functools32 == 3.2.3-2 05:52:31 INFO - jsonschema == 2.5.1 05:52:31 INFO - manifestparser == 1.1 05:52:31 INFO - mozInstall == 1.12 05:52:31 INFO - mozcrash == 0.17 05:52:31 INFO - mozdebug == 0.1 05:52:31 INFO - mozdevice == 0.48 05:52:31 INFO - mozfile == 1.2 05:52:31 INFO - mozhttpd == 0.7 05:52:31 INFO - mozinfo == 0.9 05:52:31 INFO - mozleak == 0.1 05:52:31 INFO - mozlog == 3.3 05:52:31 INFO - moznetwork == 0.27 05:52:31 INFO - mozprocess == 0.23 05:52:31 INFO - mozprofile == 0.28 05:52:31 INFO - mozrunner == 6.12 05:52:31 INFO - mozscreenshot == 0.1 05:52:31 INFO - mozsystemmonitor == 0.3 05:52:31 INFO - moztest == 0.7 05:52:31 INFO - mozversion == 1.4 05:52:31 INFO - psutil == 3.1.1 05:52:31 INFO - requests == 1.2.3 05:52:31 INFO - wsgiref == 0.1.2 05:52:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:52:31 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:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:31 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:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:31 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 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:31 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:52:31 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:52:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:31 INFO - 'HOME': '/Users/cltbld', 05:52:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:31 INFO - 'LOGNAME': 'cltbld', 05:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:31 INFO - 'MOZ_NO_REMOTE': '1', 05:52:31 INFO - 'NO_EM_RESTART': '1', 05:52:31 INFO - 'PAGER': '/bin/cat', 05:52:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:31 INFO - 'PWD': '/builds/slave/test', 05:52:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:31 INFO - 'SHELL': '/bin/bash', 05:52:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:31 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:31 INFO - 'USER': 'cltbld', 05:52:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:31 INFO - 'XPC_FLAGS': '0x0', 05:52:31 INFO - 'XPC_SERVICE_NAME': '0', 05:52:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:31 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:52:31 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:52:31 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:52:31 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:52:31 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:52:31 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:52:31 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:52:31 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:52:31 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:52:31 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:52:31 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:52:31 INFO - Cleaning up... 05:52:31 INFO - Return code: 0 05:52:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:52:31 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:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:31 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:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:31 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 0x10a1fda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a292618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd873da9e00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, '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.N4Crg1DRPL/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.QE6foDKI1o/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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:52:31 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:52:31 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:52:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:52:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:52:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:52:31 INFO - 'HOME': '/Users/cltbld', 05:52:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:52:31 INFO - 'LOGNAME': 'cltbld', 05:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:31 INFO - 'MOZ_NO_REMOTE': '1', 05:52:31 INFO - 'NO_EM_RESTART': '1', 05:52:31 INFO - 'PAGER': '/bin/cat', 05:52:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:31 INFO - 'PWD': '/builds/slave/test', 05:52:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:52:31 INFO - 'SHELL': '/bin/bash', 05:52:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:52:31 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:52:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:52:31 INFO - 'USER': 'cltbld', 05:52:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:52:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:31 INFO - 'XPC_FLAGS': '0x0', 05:52:31 INFO - 'XPC_SERVICE_NAME': '0', 05:52:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:52:32 INFO - Cleaning up... 05:52:32 INFO - Return code: 0 05:52:32 INFO - Running post-action listener: _resource_record_post_action 05:52:32 INFO - Running post-action listener: _start_resource_monitoring 05:52:32 INFO - Starting resource monitoring. 05:52:32 INFO - [mozharness: 2016-08-31 12:52:32.191082Z] Finished create-virtualenv step (success) 05:52:32 INFO - [mozharness: 2016-08-31 12:52:32.192077Z] Running install step. 05:52:32 INFO - Running pre-action listener: _resource_record_pre_action 05:52:32 INFO - Running main action method: install 05:52:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:52:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:52:32 INFO - Reading from file tmpfile_stdout 05:52:32 INFO - Detecting whether we're running mozinstall >=1.0... 05:52:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:52:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:52:32 INFO - Reading from file tmpfile_stdout 05:52:32 INFO - Output received: 05:52:32 INFO - Usage: mozinstall [options] installer 05:52:32 INFO - Options: 05:52:32 INFO - -h, --help show this help message and exit 05:52:32 INFO - -d DEST, --destination=DEST 05:52:32 INFO - Directory to install application into. [default: 05:52:32 INFO - "/builds/slave/test"] 05:52:32 INFO - --app=APP Application being installed. [default: firefox] 05:52:32 INFO - mkdir: /builds/slave/test/build/application 05:52:32 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:52:32 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:53:06 INFO - Reading from file tmpfile_stdout 05:53:06 INFO - Output received: 05:53:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:53:06 INFO - Running post-action listener: _resource_record_post_action 05:53:06 INFO - [mozharness: 2016-08-31 12:53:06.996211Z] Finished install step (success) 05:53:06 INFO - [mozharness: 2016-08-31 12:53:06.996378Z] Running run-tests step. 05:53:06 INFO - Running pre-action listener: _resource_record_pre_action 05:53:06 INFO - Running main action method: run_tests 05:53:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:53:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:53:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:53:07 INFO - 2016-08-31 05:53:07.011 screenresolution[1915:18245] starting screenresolution argv=screenresolution get 05:53:07 INFO - 2016-08-31 05:53:07.014 screenresolution[1915:18245] Display 0: 1600x1200x32@60 05:53:07 INFO - 2016-08-31 05:53:07.019 screenresolution[1916:18247] starting screenresolution argv=screenresolution list 05:53:07 INFO - Available Modes on Display 0 05:53:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:53:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:53:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:53:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:53:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:53:07 INFO - Intel Iris: 05:53:07 INFO - Chipset Model: Intel Iris 05:53:07 INFO - Type: GPU 05:53:07 INFO - Bus: Built-In 05:53:07 INFO - VRAM (Dynamic, Max): 1536 MB 05:53:07 INFO - Vendor: Intel (0x8086) 05:53:07 INFO - Device ID: 0x0a2e 05:53:07 INFO - Revision ID: 0x0009 05:53:07 INFO - Displays: 05:53:07 INFO - Display: 05:53:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:53:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:53:07 INFO - Display Serial Number: 1600x1200 60 05:53:07 INFO - Main Display: Yes 05:53:07 INFO - Mirror: Off 05:53:07 INFO - Online: Yes 05:53:07 INFO - Rotation: Supported 05:53:07 INFO - Return code: 0 05:53:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:53:07 INFO - Python 2.7.3 05:53:07 INFO - Return code: 0 05:53:07 INFO - grabbing minidump binary from tooltool 05:53:07 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:53:07 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 0x10a161d30>, '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 0x109ec6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a159b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:53:07 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:53:07 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:53:07 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:53:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:53:07 INFO - Return code: 0 05:53:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:53:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:53:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:53:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:53:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:53:07 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/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--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:53:07 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/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0322 --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:53:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N4Crg1DRPL/Render', 05:53:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:53:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:53:07 INFO - 'HOME': '/Users/cltbld', 05:53:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:53:07 INFO - 'LOGNAME': 'cltbld', 05:53:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:53:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:53:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:07 INFO - 'MOZ_NO_REMOTE': '1', 05:53:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:53:07 INFO - 'NO_EM_RESTART': '1', 05:53:07 INFO - 'PAGER': '/bin/cat', 05:53:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:53:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:07 INFO - 'PWD': '/builds/slave/test', 05:53:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:53:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:53:07 INFO - 'SHELL': '/bin/bash', 05:53:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QE6foDKI1o/Listeners', 05:53:07 INFO - 'TMPDIR': '/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/', 05:53:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:53:07 INFO - 'USER': 'cltbld', 05:53:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:53:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:53:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:07 INFO - 'XPC_FLAGS': '0x0', 05:53:07 INFO - 'XPC_SERVICE_NAME': '0', 05:53:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:53:07 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/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0322', '--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:53:07 INFO - mozversion application_buildid: 20160831044320 05:53:07 INFO - mozversion application_changeset: a328778db08dbbe7111f0c7948a05b0bca719373 05:53:07 INFO - mozversion application_display_name: Nightly 05:53:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:53:07 INFO - mozversion application_name: Firefox 05:53:07 INFO - mozversion application_remotingname: firefox 05:53:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:53:07 INFO - mozversion application_vendor: Mozilla 05:53:07 INFO - mozversion application_version: 51.0a1 05:53:07 INFO - mozversion platform_buildid: 20160831044320 05:53:07 INFO - mozversion platform_changeset: a328778db08dbbe7111f0c7948a05b0bca719373 05:53:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:53:07 INFO - mozversion platform_version: 51.0a1 05:53:07 INFO - using testdate: 1472647987 05:53:07 INFO - actual date: 1472647987 05:53:07 INFO - starting webserver on 'localhost:56114' 05:53:07 INFO - SUITE-START | Running 2 tests 05:53:07 INFO - TEST-START | dromaeo_css 05:53:07 INFO - operating with platform_type : mac_ 05:53:07 INFO - Initialising browser for dromaeo_css test... 05:53:07 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpy2fjRk/profile http://localhost:56114/getInfo.html) 05:53:10 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 05:53:10 INFO - PROCESS | 1926 | colorDepth:24 05:53:10 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 05:53:10 INFO - PROCESS | 1926 | __metrics 05:53:10 INFO - PROCESS | 1926 | 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:53:10 INFO - PROCESS | 1926 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:53:10 INFO - TEST-INFO | 1926: exit 0 05:53:10 INFO - Browser initialized. 05:53:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:53:10 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpy2fjRk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:53:22 INFO - PROCESS | 1928 | RSS: Main: 234868736 05:53:22 INFO - PROCESS | 1928 | 05:54:13 INFO - PROCESS | 1928 | 1472648053638 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:56:25 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56114/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56114/tests/dromaeo/cssquery-ext.html) 05:56:26 INFO - PROCESS | 1928 | RSS: Main: 338722816 05:56:26 INFO - PROCESS | 1928 | 05:59:29 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56114/tests/dromaeo/cssquery-ext.html (next: http://localhost:56114/tests/dromaeo/cssquery-jquery.html) 05:59:30 INFO - PROCESS | 1928 | RSS: Main: 370434048 05:59:30 INFO - PROCESS | 1928 | 06:02:32 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56114/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56114/tests/dromaeo/cssquery-mootools.html) 06:02:33 INFO - PROCESS | 1928 | RSS: Main: 336465920 06:02:33 INFO - PROCESS | 1928 | 06:05:35 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56114/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56114/tests/dromaeo/cssquery-prototype.html) 06:05:36 INFO - PROCESS | 1928 | RSS: Main: 342347776 06:05:36 INFO - PROCESS | 1928 | 06:08:39 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56114/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56114/tests/dromaeo/cssquery-yui.html) 06:08:40 INFO - PROCESS | 1928 | RSS: Main: 323555328 06:08:40 INFO - PROCESS | 1928 | 06:11:43 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56114/tests/dromaeo/cssquery-yui.html (next: http://localhost:56114/tests/dromaeo/cssquery-dojo.html) 06:11:43 INFO - PROCESS | 1928 | RSS: Main: 471986176 06:11:43 INFO - PROCESS | 1928 | 06:11:43 INFO - PROCESS | 1928 | __start_tp_report 06:11:43 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 06:11:43 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 06:11:43 INFO - PROCESS | 1928 | |i|pagename|runs| 06:11:43 INFO - PROCESS | 1928 | |0;dojo.html;3303.6696330366967;3323.651016643254;3324.5013248012797;3338.931541906591;3383.4755612880012;5614;5670.177824215486;5702.201691763156;5760.04959181735;5773.220615216945;5704.172894930232;5719.828405147852;5778.959787238296;5810.709464526778;5812.680302583356;5150.484951504838;5164.586833053366;5241.633085684004;5241.685498870061;5325.440828712987;5314.176302673091;5319.148936170213;5352.384475785276;5357.946420535804;5388.110961691316;5166.819161329336;5175.585953123778;5207.401148867891;5217.765200565946;5220.817271395502;5206.401263854667;5225.738713064369;5352.170413585901;5361.624686271963;5363.597730170214;5130.846074617767;5180.740962951875;5218.425973142951;5235.528802407764;5237.47625237477;5735.369109397963;5750.453706897838;5753.108268218432;5765.509931655814;5810.477057064884;5914.112883067532;5946.732397042144;6136.12084543884;6203.534734894856;6207.720652570645;6243.063540468966;6274.968625156845;6280.497560195174;6294.842628934268;6319.778807741707;5815.302163740336;5824.3593204748295;5833.358330583717;5842.152887831241;5854.882902341929;5802.303723553201;6194.690265486708;6233.688315584202;6277.7175027123885;6293.77971770995;6170.629762214281;6170.845728856814;6175.382461753788;6180.721867515973;6188.381161883775;6206.503479721611;6261.154744109487;6261.342559031236;6273.686315684197;6275.435210830956;6110.052941794029;6120.904292401208;6143.941779503827;6197.690115494207;6237.376262373817;6207.410296021871;6283.434490895841;6285.497160227171;6300.905486417708;6307.274663413767;4970.90058198834;4980.576650984707;4986.227134794112;4995.925061124086;5003.749812509433;4361.563843615613;4365.515724653569;4423.867283981485;4474.798634061475;4498;5676.489115979582;5736.799212027642;5737.3688894221605;5758.193852860602;5798.304203495608;6237.906431403532;6271.341509141565;6273.560850740496;6280.780172694025;6293.30773614902;5591.6365436246515;5652.943470565324;5694.402087780723;5716.828495145152;5730.598858079894;5320.707361095177;5356.65181763192;5358.91961620576;5388.407275199725;5440.3743569489225;5555.916661250165;6027.78902738402;6280.874382512233;6285.434310911948;6292.779752708725;4809.44691360484;6120.782376608402;6194.938050619436;6224.657643829632;6232.844178895564;6242.094896240111;6256;6287.811365659038;6345.777897773556;6376.043593461018;6166.969165154145;6186.288576813724;6205.22434695663;6207.782727604512;6224.751009959551;6110.012029148408;6264.123022776724;6292.276388215414;6308.936910630835;6314.336994615499;5867.794627188028;6000.699965001645;6002.0996850472775;6104.489471593237;6107.795737660903;6099.237595300588;6116.480099191441;6120.846978825565;6123.785667501616;6138.416850399205;5782.710864456844;5877.294724633071;5923.733431995484;5942.049272116441;5942.821715348547;5043.394792624908;6054.8183554493435;6084.969575152096;6148;6150.2927163375;5920.822375328747;5954.821355359346;5963.403659634002;5965.791197308074;5984.222051133324 06:11:43 INFO - PROCESS | 1928 | |1;ext.html;469.52108848974046;473.7370759228628;475.91016572050006;528.4794477439724;543.3289886989564;30704.84647576762;32151.67848321516;32154.03537893864;32170.517442238386;32293.67706322936;32459.51310730341;41648.79175604125;42162.578374216246;42185.78907105468;44273.55726442735;9764.414135151877;9910.90089099107;9940.502974851228;9942.353747006458;9947.850782238273;9793.6082556698;9874.308798384116;9906.801863962717;9909.454979976099;9917.404955702681;8946.463212207256;9191.770205744842;12100.697482562962;12395.132340736152;12398.628041158734;12277.938610306936;12335;12367.567135150226;12372.876271237308;12379.62861114168;8826.691065812665;11943.880561194364;12043.458044388024;12064.215825971283;12133.696657583543;38649.42025869614;39187.80406097951;39372.40941385881;40625.593744062775;40853.59146408557;3278.3115545735423;3298.950515742242;3299.686529779667;3348.882789102394;3669.669729724311;30362.240943976358;30442;30566.08301750951;30884.382312353628;31702.36595268082;3252.121927079675;3257.6742325767473;3266.934661306784;3353.245519758035;3376.983115084409;440845;442981;444308.7784561089;444465;446619;59597;59896.401035989955;59977.10034349488;59995;60116.988301169535;54778.45221547813;62191.689041554506;63208.05187922185;63240.36759632345;67175.9312492817;15366.692666146615;15917.761233581505;15952;15967.760483592756;15996.840031599535;12154;12281.140320177681;12283;12293.0780191486;12296.385180740926;15343.539693809204;15657.711880504994;17580.560485987953;17590.2084406202;17596.736048959276;5529.30883639545;6693.924883286197;6733.562318449285;6753.932460675429;6812.4550035997;916.7845556294266;929.4702019848621;943.8867335919733;995.4922989275377;1012.119456073221;24116;28366.85816570945;29330;29597.816087356263;30274.394512110073;950.6482601437374;1046.1892033673964;1099.3458891959272;1121.2991880074953;1203.1337437045313;13340.866591333963;14871.925640371945;15153;15365.6158596036;15504;646719;650598.7470062714;652455;655308.7234563797;656187;48059.79850503765;54377.38716579682;55518.44481555214;56127.71936140293;56243;1332.7334533093226;1352.5198554513204;1375.2917247617686;1375.9174449533261;1381.378379729126;30066.549001764553;30525.573791800507;30643.387132256783;31096.68903310938;31478.527822083146;49781;50189.49810501848;50195;50358.74820625874;50423;792637;818907;819503;819875;822563;25243.87378063098;25420.49159016846;25487.6176857351;25514;25580.23259302224;44272.89317767082;44644;45019.549804501534;45233.77383113063;45830.54169458263;36976;37337.81331093327;37640;38018.619813802616;38039;35908.640913591575;36470.27059458849;36641.08397290089;37658.8117059413;37941.430878536295;36481.087972800895;36870;37284.62715372811;37633.81183094067;37914.0521486965;31821.20446988844;33228.50157247692;33300.500492492145;33316.66683333136;33370.83314583411;23933.826834897227;24631.137910173056;24716.25851224466;25057.373565661004;25208.36979075538 06:11:43 INFO - PROCESS | 1928 | |2;jquery.html;355.9519464872241;375.80082556245185;376.21559049382034;384.6345971327239;404.58530006743086;15562.144082075496;15701.84298157018;20028.49928751778;20710;20761.961901904902;16842.83157168428;18698;19294.421167364966;19906.800931990674;19932.900335498303;4388.626966707834;5817.8836423271505;6053.788117415891;6120.571559990803;6127.87744245117;5848.4736373726355;6680.93319066808;6713.2951040140815;6726.226483954359;6773.4242589379965;5769.24999750034;6051.062085376751;6118.265808103011;6656.866862662744;6666.3000384959605;6044.164289561333;6065.939340606604;6105.694715264219;6109.480694141003;6113.969430152865;4882.609391248691;5572.665640061609;5908.20239267702;5913.994620914455;5925.259342582177;13325.73348533024;13347.199168049949;13376.197428154244;13534.187948723009;13591.592252232449;2125;2171.7936796004356;2258;2265.5468906218657;2744.4511097780323;9096.499692516909;9681.128698417177;10123.29136960413;13583.52457663987;13961.930190349085;1705.7441383792407;1833.8074502177346;1836.2104295153079;1926.5568919148534;1978.3372570189003;117162.41418792852;127492;127737;129071.35464322713;129693;8935.865962010444;11258.887411125948;11466.713332166762;11654.592089277005;11661.24201926872;14455.566333010027;14458.710825783426;14506;14510.709785804225;14518.419263229564;8080.676772929017;8380.664773408995;8381.664733410595;8513.466911231893;8524.147585241426;8191.672333106728;8232.753017409368;8271.875921861178;8376.036755773042;8408.663653453916;4494.303382975644;4498.910021799546;4503.369528266046;4509.12072145935;4582;1849.38045754671;1900.980990190108;1902.6289873474816;1918.9136488857755;1931.8119356595873;852.7612268564811;904.9411788233743;924.2190349154953;927.3276874266103;934.9392289501161;16693.415730449375;16916.830831691525;20805;21160.941952902293;21313.040913158944;799.5962039170182;894.4543828264754;929.656027269901;944.1738478831023;958.8226178157063;9265.907340926638;10656.627018054485;10702.357858528514;11247.943760281147;11462.885371146182;571041;583397;590740.0462997657;598886;602478;102914;130871;132428;137195.6280437203;140315.29842350722;939.3424602778083;954.2652157838426;978.0561757903515;979.3878825733916;980.5930538826307;18787.34244301443;19050.333238336592;19075.236990520778;19141.521461963563;19229.230830766614;52577.7371113142;53351.59917803786;53710.92578148493;55263.15789473665;55904.72047639736;78465.19185101862;79503.60248198723;79782.6010869942;79840;81359.5932020336;5405.945940540544;6176.66028368444;7355.521891077063;7406.333429991111;7709.9614501927135;10334.896651033394;11056.502457389597;12189.390530473263;12420.565280215149;12730.23618582888;10426.113780328757;10907.890921091004;10913.727156821144;10969.890301096886;11067.72330691739;9985.301028927905;10355.741106472398;10404.58381664725;10632.202584806017;10843.24097313212;9416.858747118935;9449.29130315243;10198.847017294598;11060;11142.220044596801;7731;7939.2060793920145;7985;8204.794880127805;8222.17778222173;10203.897961020295;10854.40300783433;11053.392063436588;11177.441127943734;11197.832032519682 06:11:43 INFO - PROCESS | 1928 | |3;mootools.html;1363.9386227619757;1518.6355274734065;1537.0700726060732;1731.402666080202;1896.819802118799;7857.4892631978955;8406.24343809057;8410.873836892451;8416.410851240416;8724.65101395945;8728.781780455492;8964.641414343418;8968;8969.955150224256;8974.775630609238;2371.1700904683307;2397.5564520563667;2398.07674045492;2398.940026499334;2399.076355603088;2081.396395045435;2350.6003979323555;2370.905163793455;2380.357303528055;2401.9159329423474;2126.0857831132607;2330.4406942333885;2359.6932398788135;2369.9407514812096;2386.510765293119;2352.658864464643;2375.881205939696;2388.749181335952;2391.5695174868515;2395.844270122454;2343.847649902768;2370.1704403458652;2373.1456675596773;2373.905043798246;2374.9525009499885;8965.865512017324;9069.863952040725;9070.410423322463;9093.181613654737;9104.226140778042;5045.394552653668;5296.894062118736;5462.180672899097;5465.480779325958;5465.9180112298345;3694.612065733086;3791.6966642668795;3808.3525800613684;3811.3901775715754;3816.2749077675153;1099.5162128663362;1115.1859142825701;1127.9492422840992;1133.218079525125;1138.049728476723;11515.76968460626;11773.352465614387;11795.29228246308;11861;12034.037277017906;4484.753338566345;4493.168763778712;4695.835645752382;4701.153792317347;4702.95297047032;9366.812663746823;9375.953120234355;9379.765505862271;9416.858747118798;9456.432614043179;5358.866028349323;5361.169018802092;5363.597730170253;5368.5436737876935;5383.757730902041;4431.0694754102;4541.069080838421;4591;4810.302506136591;5045.5459008689395;3740.4202348636004;3758.981205093957;3874.578388507183;3893.6301051400073;3916.2167566486814;1162.9941850290695;1182.4678894497338;1184.1829137894824;1192.9045676345872;1194.8267501212276;759.4911409355744;858.7466697324304;898.4519443139679;914.8490499067713;940.7836197674574;5777;5985.521158307325;6015.127806468037;6151.876962460726;6172.444479996821;739.9186089530255;897.9551022448851;915.6703586708779;944.0795224655906;955.2119501411363;3902.765834049966;3925.862594809168;4009.133469569272;4024.0342317843506;4025.7383270087353;10632.734181645521;10881.727956801144;11044.33733975964;11046.447677616086;11048.337099774039;9320.487373194539;10977.725556860983;10978.890211097787;11002.394868282321;11003.889961100447;7792.84414311722;8148.67405303793;8190.672373105128;8281.792955176168;8306.626201820933;5812.82561523155;5887.470127688529;5951.036158067914;6037.547183961308;6223.719932602953;9761.365511241747;9974.950125249326;10258.384496930208;10262.127719143957;10266.384016959006;10324.43215623148;10406.791864162826;10409.635662751767;10420.895791041992;10474.68575942723;7012.123484564429;7286.453515986216;7457.179710231979;7596.08846938371;7612.543247405173;8152.144024877302;8336.958315208385;8509.531975741145;8618.138186181332;8622.741317760478;7531.096268447603;7655.770326890202;7669.1947345527915;7740.806479838049;7822.569758663328;7444.330010299099;7468.2158373372185;7469.551826890404;7610.353119984863;7616.619169041636;7071.328223818729;7205.71177152908;7208.207097219197;7208.387287080657;7211.531250468913;4970.652054356305;5130.916691192188;5145.9228111579105;5180.948190518197;5203.375594928481;4971.154903666313;4992.625553083606;5068.619853510928;5135.820246291362;5275.973620131875 06:11:43 INFO - PROCESS | 1928 | |4;prototype.html;298.97608191344693;302.5779038241653;311.07145171662586;314.03590975704594;315.96524382317943;4757.333973243749;5298.1058921692575;5316.5215130638235;5355.410904800468;5452.945470545294;4372.366006928994;5135.7688903999315;5189.870253243671;5291.100512912804;5325.520703136717;3178.7615928805376;4127.607877251671;4162.625363717265;4199.685023623225;4211.620954114129;3723.478712980185;4036.9503928978384;4101.871985204063;4149.917001659965;4203.47456567929;3398.5072164536127;4078.714489985702;4092.386142078682;4097.0371962588915;4122.443470131524;4065.3698676705308;4072.5927407259333;4076.184763047402;4113.794310284504;4116.711830171889;3568.37553428149;3577.0878426001204;3583.7133029357587;3590.533230679995;3608.386574282378;4643.744594047326;4857.53853383928;4975.701457912501;5009.67243680425;5237.711925844077;4693.943862630915;4696;4727.361806156159;4728.361671174416;4732.408448943882;4519.322101684741;5456.890862182734;5507.807226747084;5514.751836167382;5563.219769673706;3739.1773809761808;3909.9218015639813;3922.8234729437245;3927.293087244295;3929.783861887595;5870.412958704095;5875.265591801025;5879.324907167229;5913.349531551526;5938.406159384027;4028.939565906514;4031.3952907064177;4040;4049.7570145791346;4110.712250142521;3811.866584669523;4020.43713880057;4023.195360927826;4035.3139966205817;4077.7757223353;4631.073785242965;4632.513586073481;4639.935228823141;4698.271767875985;4712.552307530779;4752.263399173134;5094.821681241138;5699.774548472098;5737.221461346996;5742.971285143548;1169.5789515774316;1295.902807289457;1327.2368388176644;1360.3130419138272;1362.3937347880099;836.6195776967929;913.6208473483563;926.7266156483853;940.5391358234552;942.5381563034201;4152;5200.53943359471;5224.085784987612;5225.738713064407;5265.605079619044;5156.92264616031;5160.767765450564;5276.762545685453;5401.4868587484125;5718.828435146952;4957.82647607332;5125.4879810456005;5158.767855446514;5176.378834539879;5207.531322181022;2774.458980498783;2979.3147576057195;3042;3043.802152860101;3055.762416221434;5359.490848369399;5746.511546518509;6012.054093494156;6086.786962456381;6113.388661133851;5224.817131400383;5559.916601250984;5767;5895.467178533527;5952.3154837193415;5283.2075188722;6004.789832355847;6008.459238668541;6023.602769977941;6038.6678732670125;4696.647751418589;4907.68100073494;5702.885942281214;5816.70916454184;5835.599456130583;4816.590589799766;5045;5584.832455026356;5606.551475882001;5642.576806739429;5044.394672639307;5150.665206761549;5709.858028394421;6001.219841420588;6042.667653279112;3348.8660453581583;3649.671529562352;3866.9806650966566;3901.7319371203903;3903.2583809076186;5534.833954981357;5740.302058032718;5812.418758124323;5816.47651711348;5835.44563266489;5539.307586551681;5571.3871474137;5581.5255703265675;5581.6930068846605;5586.944130558805;4469.642428605769;5131.692098474104;5241.816536421207;5560.388357280615;5646.632968857011;4588.862334129848;5043.117454445531;5275.340582427197;5377.327834020747;5453.536449401687;4794.95205047959;5068.949310506848;5335.493128152819;5671.958095085683;5675.886482270249;5533.280673512418;5552.083906155601;5581;5610.597350662334;5624.184493248538 06:11:43 INFO - PROCESS | 1928 | |5;yui.html;719.2412005334372;757.3146302596149;790.3124281874766;793.4445887878486;797.8484088023272;14753.557393278206;14945.626359341022;15450.845491545082;15470.149141797197;15471.69056618867;14095.71808563828;14164.433422663107;14266.785998210034;14292.142471451694;14742;834.3908946469066;856.0540602634093;856.7601071699903;856.7943693513542;858.8754630578562;672.3411057163983;691.1705952856568;697.9127609048869;850.0946491986045;854.4531499840107;730.0254160695478;731.8828987362023;883.4610887358738;919.1406035356929;922.8846394200725;911.9908800911973;914.679862048281;914.8810654614924;917.2111983694018;918.3800934369274;667.6695035957184;670.2894931372712;670.3497607320891;671.5332843673649;671.5500614588237;11942.940285298519;12387.256764594063;12415.875841241563;12437.440315185839;14426.350814213385;12170.939145304217;12897.613071607866;14066.437342506288;14088.2955852208;14150.929245353811;11609.883901160965;11743;11746.236494627821;11800.940995295054;11937.880621193764;3732.1789206374824;3766.679832214265;3776.6034566370686;3839.1937693084487;3850.4224366344993;102555.4872225634;104487.43268850974;104728;106147;106787;10864.78270434587;10910.290831095954;10982.835257471144;11090.279131856403;11294.37880916541;21699.915004249724;21728.609852916168;21732;21736.673949890763;21770.455738606663;7626.084869815658;7772.18392068836;7784.41616878725;7837.09873364559;8032.236937491046;4245.766482843474;4352.238358287287;4355.825766969349;4481.5070342262325;4499.1451624191295;3425.1094715374184;3554.608993010767;3554.928901421957;3556.78659280444;3567.8216089195435;634.6002018728263;646.2664875366399;646.5183438338468;658.5291516565583;660.8215781738903;572.2046355565767;587.2512546503242;615.3231445410013;623.8440389902524;638.4254171245823;7821.960890195513;8221.889055472264;8942.46345219289;9072.95463522678;9112.271018318519;547.5455372041196;571.9599628026082;581.8370856160282;647.4011539326199;661.1009027722293;4293.3130699088;4461.643068554507;4515.616172625364;4535.165521549032;4587.564181402761;101785.98214017766;101987.6819708843;138983;160714.19642901712;165564;138376;139668;140778.59221407858;144039.11921761715;146066;97987.51006244923;117953;121604.39197803954;122315;124914.12628810757;8504.659813607386;8595.570221489026;8823.64705411802;8856.867146992672;8885.822283554164;52084.47915520796;66426;66537.6827896513;67562.66218668876;67710.61446927732;96553;97507;97720.51139744256;98644.50677746565;98979.50510247402;11794;11855.644330670093;11906.285622862655;11932.880671193525;12393.070519710878;12664.240145591293;12998.090133690552;13059.085863989429;13101.868981310065;13230.669233269246;11230.775384492428;11544.353154156843;11896.167268291136;12034.939825300817;12399.00807935381;11778.27945818531;11991.400429978641;12019.519219231483;12232.571859984857;12458.127931044739;10402;11845.585404511145;11854.822177667515;12024.519019239133;12133.75732485363;9666.56500457467;9822.361546499455;10151.949240254047;10282.948585257025;10651.147908167173;8324.83350333002;9075.682351117679;9792.79674537328;9964.953679863509;10039.397636141855 06:11:43 INFO - PROCESS | 1928 | __end_tp_report 06:11:43 INFO - PROCESS | 1928 | __start_cc_report 06:11:43 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,1378 06:11:43 INFO - PROCESS | 1928 | __end_cc_report 06:11:43 INFO - PROCESS | 1928 | __startTimestamp1472649103759__endTimestamp 06:11:43 INFO - PROCESS | 1928 | 06:11:43 INFO - PROCESS | 1928 | ------- Summary: start ------- 06:11:43 INFO - PROCESS | 1928 | Number of tests: 6 06:11:43 INFO - PROCESS | 1928 | 06:11:43 INFO - PROCESS | 1928 | [#0] dojo.html Cycles:165 Average:5733.06 Median:5914.11 stddev:633.37 (10.7%) stddev-sans-first:605.96 06:11:43 INFO - PROCESS | 1928 | Values: 3303.7 3323.7 3324.5 3338.9 3383.5 5614.0 5670.2 5702.2 5760.0 5773.2 5704.2 5719.8 5779.0 5810.7 5812.7 5150.5 5164.6 5241.6 5241.7 5325.4 5314.2 5319.1 5352.4 5357.9 5388.1 5166.8 5175.6 5207.4 5217.8 5220.8 5206.4 5225.7 5352.2 5361.6 5363.6 5130.8 5180.7 5218.4 5235.5 5237.5 5735.4 5750.5 5753.1 5765.5 5810.5 5914.1 5946.7 6136.1 6203.5 6207.7 6243.1 6275.0 6280.5 6294.8 6319.8 5815.3 5824.4 5833.4 5842.2 5854.9 5802.3 6194.7 6233.7 6277.7 6293.8 6170.6 6170.8 6175.4 6180.7 6188.4 6206.5 6261.2 6261.3 6273.7 6275.4 6110.1 6120.9 6143.9 6197.7 6237.4 6207.4 6283.4 6285.5 6300.9 6307.3 4970.9 4980.6 4986.2 4995.9 5003.7 4361.6 4365.5 4423.9 4474.8 4498.0 5676.5 5736.8 5737.4 5758.2 5798.3 6237.9 6271.3 6273.6 6280.8 6293.3 5591.6 5652.9 5694.4 5716.8 5730.6 5320.7 5356.7 5358.9 5388.4 5440.4 5555.9 6027.8 6280.9 6285.4 6292.8 4809.4 6120.8 6194.9 6224.7 6232.8 6242.1 6256.0 6287.8 6345.8 6376.0 6167.0 6186.3 6205.2 6207.8 6224.8 6110.0 6264.1 6292.3 6308.9 6314.3 5867.8 6000.7 6002.1 6104.5 6107.8 6099.2 6116.5 6120.8 6123.8 6138.4 5782.7 5877.3 5923.7 5942.0 5942.8 5043.4 6054.8 6085.0 6148.0 6150.3 5920.8 5954.8 5963.4 5965.8 5984.2 06:11:43 INFO - PROCESS | 1928 | 06:11:43 INFO - PROCESS | 1928 | [#1] ext.html Cycles:180 Average:75289.26 Median:20155.38 stddev:176260.82 (874.5%) stddev-sans-first:176665.76 06:11:43 INFO - PROCESS | 1928 | Values: 469.5 473.7 475.9 528.5 543.3 30704.8 32151.7 32154.0 32170.5 32293.7 32459.5 41648.8 42162.6 42185.8 44273.6 9764.4 9910.9 9940.5 9942.4 9947.9 9793.6 9874.3 9906.8 9909.5 9917.4 8946.5 9191.8 12100.7 12395.1 12398.6 12277.9 12335.0 12367.6 12372.9 12379.6 8826.7 11943.9 12043.5 12064.2 12133.7 38649.4 39187.8 39372.4 40625.6 40853.6 3278.3 3299.0 3299.7 3348.9 3669.7 30362.2 30442.0 30566.1 30884.4 31702.4 3252.1 3257.7 3266.9 3353.2 3377.0 440845.0 442981.0 444308.8 444465.0 446619.0 59597.0 59896.4 59977.1 59995.0 60117.0 54778.5 62191.7 63208.1 63240.4 67175.9 15366.7 15917.8 15952.0 15967.8 15996.8 12154.0 12281.1 12283.0 12293.1 12296.4 15343.5 15657.7 17580.6 17590.2 17596.7 5529.3 6693.9 6733.6 6753.9 6812.5 916.8 929.5 943.9 995.5 1012.1 24116.0 28366.9 29330.0 29597.8 30274.4 950.6 1046.2 1099.3 1121.3 1203.1 13340.9 14871.9 15153.0 15365.6 15504.0 646719.0 650598.7 652455.0 655308.7 656187.0 48059.8 54377.4 55518.4 56127.7 56243.0 1332.7 1352.5 1375.3 1375.9 1381.4 30066.5 30525.6 30643.4 31096.7 31478.5 49781.0 50189.5 50195.0 50358.7 50423.0 792637.0 818907.0 819503.0 819875.0 822563.0 25243.9 25420.5 25487.6 25514.0 25580.2 44272.9 44644.0 45019.5 45233.8 45830.5 36976.0 37337.8 37640.0 38018.6 38039.0 35908.6 36470.3 36641.1 37658.8 37941.4 36481.1 36870.0 37284.6 37633.8 37914.1 31821.2 33228.5 33300.5 33316.7 33370.8 23933.8 24631.1 24716.3 25057.4 25208.4 06:11:43 INFO - PROCESS | 1928 | 06:11:43 INFO - PROCESS | 1928 | [#2] jquery.html Cycles:180 Average:34575.33 Median:380.43 stddev:98828.14 (25978.3%) stddev-sans-first:99071.98 06:11:43 INFO - PROCESS | 1928 | Values: 356.0 375.8 376.2 384.6 404.6 15562.1 15701.8 20028.5 20710.0 20762.0 16842.8 18698.0 19294.4 19906.8 19932.9 4388.6 5817.9 6053.8 6120.6 6127.9 5848.5 6680.9 6713.3 6726.2 6773.4 5769.2 6051.1 6118.3 6656.9 6666.3 6044.2 6065.9 6105.7 6109.5 6114.0 4882.6 5572.7 5908.2 5914.0 5925.3 13325.7 13347.2 13376.2 13534.2 13591.6 2125.0 2171.8 2258.0 2265.5 2744.5 9096.5 9681.1 10123.3 13583.5 13961.9 1705.7 1833.8 1836.2 1926.6 1978.3 117162.4 127492.0 127737.0 129071.4 129693.0 8935.9 11258.9 11466.7 11654.6 11661.2 14455.6 14458.7 14506.0 14510.7 14518.4 8080.7 8380.7 8381.7 8513.5 8524.1 8191.7 8232.8 8271.9 8376.0 8408.7 4494.3 4498.9 4503.4 4509.1 4582.0 1849.4 1901.0 1902.6 1918.9 1931.8 852.8 904.9 924.2 927.3 934.9 16693.4 16916.8 20805.0 21160.9 21313.0 799.6 894.5 929.7 944.2 958.8 9265.9 10656.6 10702.4 11247.9 11462.9 571041.0 583397.0 590740.0 598886.0 602478.0 102914.0 130871.0 132428.0 137195.6 140315.3 939.3 954.3 978.1 979.4 980.6 18787.3 19050.3 19075.2 19141.5 19229.2 52577.7 53351.6 53710.9 55263.2 55904.7 78465.2 79503.6 79782.6 79840.0 81359.6 5405.9 6176.7 7355.5 7406.3 7710.0 10334.9 11056.5 12189.4 12420.6 12730.2 10426.1 10907.9 10913.7 10969.9 11067.7 9985.3 10355.7 10404.6 10632.2 10843.2 9416.9 9449.3 10198.8 11060.0 11142.2 7731.0 7939.2 7985.0 8204.8 8222.2 10203.9 10854.4 11053.4 11177.4 11197.8 06:11:43 INFO - PROCESS | 1928 | 06:11:43 INFO - PROCESS | 1928 | [#3] mootools.html Cycles:180 Average:5758.20 Median:5057.08 stddev:3230.91 (63.9%) stddev-sans-first:3223.09 06:11:43 INFO - PROCESS | 1928 | Values: 1363.9 1518.6 1537.1 1731.4 1896.8 7857.5 8406.2 8410.9 8416.4 8724.7 8728.8 8964.6 8968.0 8970.0 8974.8 2371.2 2397.6 2398.1 2398.9 2399.1 2081.4 2350.6 2370.9 2380.4 2401.9 2126.1 2330.4 2359.7 2369.9 2386.5 2352.7 2375.9 2388.7 2391.6 2395.8 2343.8 2370.2 2373.1 2373.9 2375.0 8965.9 9069.9 9070.4 9093.2 9104.2 5045.4 5296.9 5462.2 5465.5 5465.9 3694.6 3791.7 3808.4 3811.4 3816.3 1099.5 1115.2 1127.9 1133.2 1138.0 11515.8 11773.4 11795.3 11861.0 12034.0 4484.8 4493.2 4695.8 4701.2 4703.0 9366.8 9376.0 9379.8 9416.9 9456.4 5358.9 5361.2 5363.6 5368.5 5383.8 4431.1 4541.1 4591.0 4810.3 5045.5 3740.4 3759.0 3874.6 3893.6 3916.2 1163.0 1182.5 1184.2 1192.9 1194.8 759.5 858.7 898.5 914.8 940.8 5777.0 5985.5 6015.1 6151.9 6172.4 739.9 898.0 915.7 944.1 955.2 3902.8 3925.9 4009.1 4024.0 4025.7 10632.7 10881.7 11044.3 11046.4 11048.3 9320.5 10977.7 10978.9 11002.4 11003.9 7792.8 8148.7 8190.7 8281.8 8306.6 5812.8 5887.5 5951.0 6037.5 6223.7 9761.4 9975.0 10258.4 10262.1 10266.4 10324.4 10406.8 10409.6 10420.9 10474.7 7012.1 7286.5 7457.2 7596.1 7612.5 8152.1 8337.0 8509.5 8618.1 8622.7 7531.1 7655.8 7669.2 7740.8 7822.6 7444.3 7468.2 7469.6 7610.4 7616.6 7071.3 7205.7 7208.2 7208.4 7211.5 4970.7 5130.9 5145.9 5180.9 5203.4 4971.2 4992.6 5068.6 5135.8 5276.0 06:11:43 INFO - PROCESS | 1928 | 06:11:43 INFO - PROCESS | 1928 | [#4] prototype.html Cycles:180 Average:4551.13 Median:5110.15 stddev:1373.96 (26.9%) stddev-sans-first:1340.24 06:11:43 INFO - PROCESS | 1928 | Values: 299.0 302.6 311.1 314.0 316.0 4757.3 5298.1 5316.5 5355.4 5452.9 4372.4 5135.8 5189.9 5291.1 5325.5 3178.8 4127.6 4162.6 4199.7 4211.6 3723.5 4037.0 4101.9 4149.9 4203.5 3398.5 4078.7 4092.4 4097.0 4122.4 4065.4 4072.6 4076.2 4113.8 4116.7 3568.4 3577.1 3583.7 3590.5 3608.4 4643.7 4857.5 4975.7 5009.7 5237.7 4693.9 4696.0 4727.4 4728.4 4732.4 4519.3 5456.9 5507.8 5514.8 5563.2 3739.2 3909.9 3922.8 3927.3 3929.8 5870.4 5875.3 5879.3 5913.3 5938.4 4028.9 4031.4 4040.0 4049.8 4110.7 3811.9 4020.4 4023.2 4035.3 4077.8 4631.1 4632.5 4639.9 4698.3 4712.6 4752.3 5094.8 5699.8 5737.2 5743.0 1169.6 1295.9 1327.2 1360.3 1362.4 836.6 913.6 926.7 940.5 942.5 4152.0 5200.5 5224.1 5225.7 5265.6 5156.9 5160.8 5276.8 5401.5 5718.8 4957.8 5125.5 5158.8 5176.4 5207.5 2774.5 2979.3 3042.0 3043.8 3055.8 5359.5 5746.5 6012.1 6086.8 6113.4 5224.8 5559.9 5767.0 5895.5 5952.3 5283.2 6004.8 6008.5 6023.6 6038.7 4696.6 4907.7 5702.9 5816.7 5835.6 4816.6 5045.0 5584.8 5606.6 5642.6 5044.4 5150.7 5709.9 6001.2 6042.7 3348.9 3649.7 3867.0 3901.7 3903.3 5534.8 5740.3 5812.4 5816.5 5835.4 5539.3 5571.4 5581.5 5581.7 5586.9 4469.6 5131.7 5241.8 5560.4 5646.6 4588.9 5043.1 5275.3 5377.3 5453.5 4795.0 5068.9 5335.5 5672.0 5675.9 5533.3 5552.1 5581.0 5610.6 5624.2 06:11:43 INFO - PROCESS | 1928 | 06:11:43 INFO - PROCESS | 1928 | [#5] yui.html Cycles:180 Average:24661.92 Median:3807.90 stddev:40290.06 (1058.1%) stddev-sans-first:40362.98 06:11:43 INFO - PROCESS | 1928 | Values: 719.2 757.3 790.3 793.4 797.8 14753.6 14945.6 15450.8 15470.1 15471.7 14095.7 14164.4 14266.8 14292.1 14742.0 834.4 856.1 856.8 856.8 858.9 672.3 691.2 697.9 850.1 854.5 730.0 731.9 883.5 919.1 922.9 912.0 914.7 914.9 917.2 918.4 667.7 670.3 670.3 671.5 671.6 11942.9 12387.3 12415.9 12437.4 14426.4 12170.9 12897.6 14066.4 14088.3 14150.9 11609.9 11743.0 11746.2 11800.9 11937.9 3732.2 3766.7 3776.6 3839.2 3850.4 102555.5 104487.4 104728.0 106147.0 106787.0 10864.8 10910.3 10982.8 11090.3 11294.4 21699.9 21728.6 21732.0 21736.7 21770.5 7626.1 7772.2 7784.4 7837.1 8032.2 4245.8 4352.2 4355.8 4481.5 4499.1 3425.1 3554.6 3554.9 3556.8 3567.8 634.6 646.3 646.5 658.5 660.8 572.2 587.3 615.3 623.8 638.4 7822.0 8221.9 8942.5 9073.0 9112.3 547.5 572.0 581.8 647.4 661.1 4293.3 4461.6 4515.6 4535.2 4587.6 101786.0 101987.7 138983.0 160714.2 165564.0 138376.0 139668.0 140778.6 144039.1 146066.0 97987.5 117953.0 121604.4 122315.0 124914.1 8504.7 8595.6 8823.6 8856.9 8885.8 52084.5 66426.0 66537.7 67562.7 67710.6 96553.0 97507.0 97720.5 98644.5 98979.5 11794.0 11855.6 11906.3 11932.9 12393.1 12664.2 12998.1 13059.1 13101.9 13230.7 11230.8 11544.4 11896.2 12034.9 12399.0 11778.3 11991.4 12019.5 12232.6 12458.1 10402.0 11845.6 11854.8 12024.5 12133.8 9666.6 9822.4 10151.9 10282.9 10651.1 8324.8 9075.7 9792.8 9965.0 10039.4 06:11:43 INFO - PROCESS | 1928 | -------- Summary: end -------- 06:11:43 INFO - PROCESS | 1928 | 06:11:44 INFO - TEST-INFO | 1928: exit 0 06:11:44 INFO - TEST-OK | dromaeo_css | took 1116773ms 06:11:44 INFO - TEST-START | kraken 06:11:44 INFO - operating with platform_type : mac_ 06:11:44 INFO - Initialising browser for kraken test... 06:11:44 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpBBGYK4/profile http://localhost:56114/getInfo.html) 06:11:46 INFO - PROCESS | 1931 | __metrics Screen width/height:1600/1200 06:11:46 INFO - PROCESS | 1931 | colorDepth:24 06:11:46 INFO - PROCESS | 1931 | Browser inner width/height: 1024/689 06:11:46 INFO - PROCESS | 1931 | __metrics 06:11:46 INFO - PROCESS | 1931 | 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:11:46 INFO - PROCESS | 1931 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:11:47 INFO - TEST-INFO | 1931: exit 0 06:11:47 INFO - Browser initialized. 06:11:47 INFO - Running cycle 1/1 for kraken test... 06:11:47 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/T/tmpBBGYK4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:11:59 INFO - PROCESS | 1932 | RSS: Main: 234594304 06:11:59 INFO - PROCESS | 1932 | 06:12:02 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56114/tests/kraken/driver.html?testName=audio-beat-detection) 06:12:03 INFO - PROCESS | 1932 | RSS: Main: 292257792 06:12:03 INFO - PROCESS | 1932 | 06:12:05 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56114/tests/kraken/driver.html?testName=audio-dft) 06:12:06 INFO - PROCESS | 1932 | RSS: Main: 288153600 06:12:06 INFO - PROCESS | 1932 | 06:12:09 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56114/tests/kraken/driver.html?testName=audio-fft) 06:12:10 INFO - PROCESS | 1932 | RSS: Main: 380534784 06:12:10 INFO - PROCESS | 1932 | 06:12:12 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56114/tests/kraken/driver.html?testName=audio-oscillator) 06:12:13 INFO - PROCESS | 1932 | RSS: Main: 280973312 06:12:13 INFO - PROCESS | 1932 | 06:12:15 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56114/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:12:16 INFO - PROCESS | 1932 | RSS: Main: 283484160 06:12:16 INFO - PROCESS | 1932 | 06:12:18 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56114/tests/kraken/driver.html?testName=imaging-darkroom) 06:12:19 INFO - PROCESS | 1932 | RSS: Main: 320577536 06:12:19 INFO - PROCESS | 1932 | 06:12:22 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56114/tests/kraken/driver.html?testName=imaging-desaturate) 06:12:23 INFO - PROCESS | 1932 | RSS: Main: 323670016 06:12:23 INFO - PROCESS | 1932 | 06:12:25 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56114/tests/kraken/driver.html?testName=json-parse-financial) 06:12:26 INFO - PROCESS | 1932 | RSS: Main: 321601536 06:12:26 INFO - PROCESS | 1932 | 06:12:28 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56114/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:12:28 INFO - PROCESS | 1932 | RSS: Main: 298967040 06:12:28 INFO - PROCESS | 1932 | 06:12:30 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56114/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:12:31 INFO - PROCESS | 1932 | RSS: Main: 304861184 06:12:31 INFO - PROCESS | 1932 | 06:12:33 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56114/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:12:34 INFO - PROCESS | 1932 | RSS: Main: 305774592 06:12:34 INFO - PROCESS | 1932 | 06:12:37 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56114/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:12:38 INFO - PROCESS | 1932 | RSS: Main: 320655360 06:12:38 INFO - PROCESS | 1932 | 06:12:40 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56114/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:12:41 INFO - PROCESS | 1932 | RSS: Main: 317018112 06:12:41 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:56114/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56114/tests/kraken/driver.html?testName=ai-astar) 06:12:43 INFO - PROCESS | 1932 | RSS: Main: 360296448 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | __start_tp_report 06:12:43 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 06:12:43 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 06:12:43 INFO - PROCESS | 1932 | |i|pagename|runs| 06:12:43 INFO - PROCESS | 1932 | |0;ai-astar;112;113;131;113;114;113;114;113;113;112 06:12:43 INFO - PROCESS | 1932 | |1;audio-beat-detection;149;109;135;162;108;132;131;107;159;108 06:12:43 INFO - PROCESS | 1932 | |2;audio-dft;184;184;183;185;186;230;249;182;183;181 06:12:43 INFO - PROCESS | 1932 | |3;audio-fft;81;63;77;180;52;81;81;92;154;58 06:12:43 INFO - PROCESS | 1932 | |4;audio-oscillator;82;106;173;100;68;90;121;68;70;121 06:12:43 INFO - PROCESS | 1932 | |5;imaging-gaussian-blur;88;85;88;85;88;84;89;85;88;86 06:12:43 INFO - PROCESS | 1932 | |6;imaging-darkroom;131;148;94;94;93;94;93;95;103;148 06:12:43 INFO - PROCESS | 1932 | |7;imaging-desaturate;85;102;135;86;85;86;86;84;86;86 06:12:43 INFO - PROCESS | 1932 | |8;json-parse-financial;55;52;53;52;72;82;83;59;53;54 06:12:43 INFO - PROCESS | 1932 | |9;json-stringify-tinderbox;65;69;65;78;73;78;70;69;96;104 06:12:43 INFO - PROCESS | 1932 | |10;stanford-crypto-aes;86;88;86;88;85;86;84;85;89;89 06:12:43 INFO - PROCESS | 1932 | |11;stanford-crypto-ccm;151;150;152;143;135;148;126;150;189;151 06:12:43 INFO - PROCESS | 1932 | |12;stanford-crypto-pbkdf2;169;169;169;170;171;172;170;170;169;170 06:12:43 INFO - PROCESS | 1932 | |13;stanford-crypto-sha256-iterative;67;67;68;67;68;69;67;82;85;84 06:12:43 INFO - PROCESS | 1932 | __end_tp_report 06:12:43 INFO - PROCESS | 1932 | __start_cc_report 06:12:43 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,2101 06:12:43 INFO - PROCESS | 1932 | __end_cc_report 06:12:43 INFO - PROCESS | 1932 | __startTimestamp1472649163538__endTimestamp 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | ------- Summary: start ------- 06:12:43 INFO - PROCESS | 1932 | Number of tests: 14 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#0] ai-astar Cycles:10 Average:114.80 Median:113.00 stddev:5.73 (5.1%) stddev-sans-first:5.99 06:12:43 INFO - PROCESS | 1932 | Values: 112.0 113.0 131.0 113.0 114.0 113.0 114.0 113.0 113.0 112.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:133.50 stddev:21.59 (16.2%) stddev-sans-first:21.77 06:12:43 INFO - PROCESS | 1932 | Values: 149.0 109.0 135.0 162.0 108.0 132.0 131.0 107.0 159.0 108.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#2] audio-dft Cycles:10 Average:194.70 Median:184.50 stddev:24.07 (13.0%) stddev-sans-first:25.22 06:12:43 INFO - PROCESS | 1932 | Values: 184.0 184.0 183.0 185.0 186.0 230.0 249.0 182.0 183.0 181.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#3] audio-fft Cycles:10 Average:91.90 Median:79.00 stddev:41.86 (53.0%) stddev-sans-first:44.22 06:12:43 INFO - PROCESS | 1932 | Values: 81.0 63.0 77.0 180.0 52.0 81.0 81.0 92.0 154.0 58.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#4] audio-oscillator Cycles:10 Average:99.90 Median:68.00 stddev:32.69 (48.1%) stddev-sans-first:34.03 06:12:43 INFO - PROCESS | 1932 | Values: 82.0 106.0 173.0 100.0 68.0 90.0 121.0 68.0 70.0 121.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 06:12:43 INFO - PROCESS | 1932 | Values: 88.0 85.0 88.0 85.0 88.0 84.0 89.0 85.0 88.0 86.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#6] imaging-darkroom Cycles:10 Average:109.30 Median:93.50 stddev:23.44 (25.1%) stddev-sans-first:23.51 06:12:43 INFO - PROCESS | 1932 | Values: 131.0 148.0 94.0 94.0 93.0 94.0 93.0 95.0 103.0 148.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:86.00 stddev:15.95 (18.6%) stddev-sans-first:16.71 06:12:43 INFO - PROCESS | 1932 | Values: 85.0 102.0 135.0 86.0 85.0 86.0 86.0 84.0 86.0 86.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:57.00 stddev:12.57 (22.1%) stddev-sans-first:13.11 06:12:43 INFO - PROCESS | 1932 | Values: 55.0 52.0 53.0 52.0 72.0 82.0 83.0 59.0 53.0 54.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#9] json-stringify-tinderbox Cycles:10 Average:76.70 Median:71.50 stddev:13.22 (18.5%) stddev-sans-first:13.32 06:12:43 INFO - PROCESS | 1932 | Values: 65.0 69.0 65.0 78.0 73.0 78.0 70.0 69.0 96.0 104.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:87.00 stddev:1.78 (2.0%) stddev-sans-first:1.87 06:12:43 INFO - PROCESS | 1932 | Values: 86.0 88.0 86.0 88.0 85.0 86.0 84.0 85.0 89.0 89.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:150.50 stddev:16.26 (10.8%) stddev-sans-first:17.23 06:12:43 INFO - PROCESS | 1932 | Values: 151.0 150.0 152.0 143.0 135.0 148.0 126.0 150.0 189.0 151.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:170.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 06:12:43 INFO - PROCESS | 1932 | Values: 169.0 169.0 169.0 170.0 171.0 172.0 170.0 170.0 169.0 170.0 06:12:43 INFO - PROCESS | 1932 | 06:12:43 INFO - PROCESS | 1932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:68.50 stddev:7.83 (11.4%) stddev-sans-first:8.06 06:12:43 INFO - PROCESS | 1932 | Values: 67.0 67.0 68.0 67.0 68.0 69.0 67.0 82.0 85.0 84.0 06:12:43 INFO - PROCESS | 1932 | -------- Summary: end -------- 06:12:43 INFO - PROCESS | 1932 | 06:12:44 INFO - TEST-INFO | 1932: exit 0 06:12:44 INFO - TEST-OK | kraken | took 60026ms 06:12:44 INFO - SUITE-END | took 1176s 06:12:44 INFO - Completed test suite (00:19:37) 06:12:44 INFO - javascript benchmark 06:12:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3303.6696330366967, 3323.651016643254, 3324.5013248012797, 3338.931541906591, 3383.4755612880012, 5614.0, 5670.177824215486, 5702.201691763156, 5760.04959181735, 5773.220615216945, 5704.172894930232, 5719.828405147852, 5778.959787238296, 5810.709464526778, 5812.680302583356, 5150.484951504838, 5164.586833053366, 5241.633085684004, 5241.685498870061, 5325.440828712987, 5314.176302673091, 5319.148936170213, 5352.384475785276, 5357.946420535804, 5388.110961691316, 5166.819161329336, 5175.585953123778, 5207.401148867891, 5217.765200565946, 5220.817271395502, 5206.401263854667, 5225.738713064369, 5352.170413585901, 5361.624686271963, 5363.597730170214, 5130.846074617767, 5180.740962951875, 5218.425973142951, 5235.528802407764, 5237.47625237477, 5735.369109397963, 5750.453706897838, 5753.108268218432, 5765.509931655814, 5810.477057064884, 5914.112883067532, 5946.732397042144, 6136.12084543884, 6203.534734894856, 6207.720652570645, 6243.063540468966, 6274.968625156845, 6280.497560195174, 6294.842628934268, 6319.778807741707, 5815.302163740336, 5824.3593204748295, 5833.358330583717, 5842.152887831241, 5854.882902341929, 5802.303723553201, 6194.690265486708, 6233.688315584202, 6277.7175027123885, 6293.77971770995, 6170.629762214281, 6170.845728856814, 6175.382461753788, 6180.721867515973, 6188.381161883775, 6206.503479721611, 6261.154744109487, 6261.342559031236, 6273.686315684197, 6275.435210830956, 6110.052941794029, 6120.904292401208, 6143.941779503827, 6197.690115494207, 6237.376262373817, 6207.410296021871, 6283.434490895841, 6285.497160227171, 6300.905486417708, 6307.274663413767, 4970.90058198834, 4980.576650984707, 4986.227134794112, 4995.925061124086, 5003.749812509433, 4361.563843615613, 4365.515724653569, 4423.867283981485, 4474.798634061475, 4498.0, 5676.489115979582, 5736.799212027642, 5737.3688894221605, 5758.193852860602, 5798.304203495608, 6237.906431403532, 6271.341509141565, 6273.560850740496, 6280.780172694025, 6293.30773614902, 5591.6365436246515, 5652.943470565324, 5694.402087780723, 5716.828495145152, 5730.598858079894, 5320.707361095177, 5356.65181763192, 5358.91961620576, 5388.407275199725, 5440.3743569489225, 5555.916661250165, 6027.78902738402, 6280.874382512233, 6285.434310911948, 6292.779752708725, 4809.44691360484, 6120.782376608402, 6194.938050619436, 6224.657643829632, 6232.844178895564, 6242.094896240111, 6256.0, 6287.811365659038, 6345.777897773556, 6376.043593461018, 6166.969165154145, 6186.288576813724, 6205.22434695663, 6207.782727604512, 6224.751009959551, 6110.012029148408, 6264.123022776724, 6292.276388215414, 6308.936910630835, 6314.336994615499, 5867.794627188028, 6000.699965001645, 6002.0996850472775, 6104.489471593237, 6107.795737660903, 6099.237595300588, 6116.480099191441, 6120.846978825565, 6123.785667501616, 6138.416850399205, 5782.710864456844, 5877.294724633071, 5923.733431995484, 5942.049272116441, 5942.821715348547, 5043.394792624908, 6054.8183554493435, 6084.969575152096, 6148.0, 6150.2927163375, 5920.822375328747, 5954.821355359346, 5963.403659634002, 5965.791197308074, 5984.222051133324], "value": 5693.216534146121, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.52108848974046, 473.7370759228628, 475.91016572050006, 528.4794477439724, 543.3289886989564, 30704.84647576762, 32151.67848321516, 32154.03537893864, 32170.517442238386, 32293.67706322936, 32459.51310730341, 41648.79175604125, 42162.578374216246, 42185.78907105468, 44273.55726442735, 9764.414135151877, 9910.90089099107, 9940.502974851228, 9942.353747006458, 9947.850782238273, 9793.6082556698, 9874.308798384116, 9906.801863962717, 9909.454979976099, 9917.404955702681, 8946.463212207256, 9191.770205744842, 12100.697482562962, 12395.132340736152, 12398.628041158734, 12277.938610306936, 12335.0, 12367.567135150226, 12372.876271237308, 12379.62861114168, 8826.691065812665, 11943.880561194364, 12043.458044388024, 12064.215825971283, 12133.696657583543, 38649.42025869614, 39187.80406097951, 39372.40941385881, 40625.593744062775, 40853.59146408557, 3278.3115545735423, 3298.950515742242, 3299.686529779667, 3348.882789102394, 3669.669729724311, 30362.240943976358, 30442.0, 30566.08301750951, 30884.382312353628, 31702.36595268082, 3252.121927079675, 3257.6742325767473, 3266.934661306784, 3353.245519758035, 3376.983115084409, 440845.0, 442981.0, 444308.7784561089, 444465.0, 446619.0, 59597.0, 59896.401035989955, 59977.10034349488, 59995.0, 60116.988301169535, 54778.45221547813, 62191.689041554506, 63208.05187922185, 63240.36759632345, 67175.9312492817, 15366.692666146615, 15917.761233581505, 15952.0, 15967.760483592756, 15996.840031599535, 12154.0, 12281.140320177681, 12283.0, 12293.0780191486, 12296.385180740926, 15343.539693809204, 15657.711880504994, 17580.560485987953, 17590.2084406202, 17596.736048959276, 5529.30883639545, 6693.924883286197, 6733.562318449285, 6753.932460675429, 6812.4550035997, 916.7845556294266, 929.4702019848621, 943.8867335919733, 995.4922989275377, 1012.119456073221, 24116.0, 28366.85816570945, 29330.0, 29597.816087356263, 30274.394512110073, 950.6482601437374, 1046.1892033673964, 1099.3458891959272, 1121.2991880074953, 1203.1337437045313, 13340.866591333963, 14871.925640371945, 15153.0, 15365.6158596036, 15504.0, 646719.0, 650598.7470062714, 652455.0, 655308.7234563797, 656187.0, 48059.79850503765, 54377.38716579682, 55518.44481555214, 56127.71936140293, 56243.0, 1332.7334533093226, 1352.5198554513204, 1375.2917247617686, 1375.9174449533261, 1381.378379729126, 30066.549001764553, 30525.573791800507, 30643.387132256783, 31096.68903310938, 31478.527822083146, 49781.0, 50189.49810501848, 50195.0, 50358.74820625874, 50423.0, 792637.0, 818907.0, 819503.0, 819875.0, 822563.0, 25243.87378063098, 25420.49159016846, 25487.6176857351, 25514.0, 25580.23259302224, 44272.89317767082, 44644.0, 45019.549804501534, 45233.77383113063, 45830.54169458263, 36976.0, 37337.81331093327, 37640.0, 38018.619813802616, 38039.0, 35908.640913591575, 36470.27059458849, 36641.08397290089, 37658.8117059413, 37941.430878536295, 36481.087972800895, 36870.0, 37284.62715372811, 37633.81183094067, 37914.0521486965, 31821.20446988844, 33228.50157247692, 33300.500492492145, 33316.66683333136, 33370.83314583411, 23933.826834897227, 24631.137910173056, 24716.25851224466, 25057.373565661004, 25208.36979075538], "value": 19923.597835477205, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.9519464872241, 375.80082556245185, 376.21559049382034, 384.6345971327239, 404.58530006743086, 15562.144082075496, 15701.84298157018, 20028.49928751778, 20710.0, 20761.961901904902, 16842.83157168428, 18698.0, 19294.421167364966, 19906.800931990674, 19932.900335498303, 4388.626966707834, 5817.8836423271505, 6053.788117415891, 6120.571559990803, 6127.87744245117, 5848.4736373726355, 6680.93319066808, 6713.2951040140815, 6726.226483954359, 6773.4242589379965, 5769.24999750034, 6051.062085376751, 6118.265808103011, 6656.866862662744, 6666.3000384959605, 6044.164289561333, 6065.939340606604, 6105.694715264219, 6109.480694141003, 6113.969430152865, 4882.609391248691, 5572.665640061609, 5908.20239267702, 5913.994620914455, 5925.259342582177, 13325.73348533024, 13347.199168049949, 13376.197428154244, 13534.187948723009, 13591.592252232449, 2125.0, 2171.7936796004356, 2258.0, 2265.5468906218657, 2744.4511097780323, 9096.499692516909, 9681.128698417177, 10123.29136960413, 13583.52457663987, 13961.930190349085, 1705.7441383792407, 1833.8074502177346, 1836.2104295153079, 1926.5568919148534, 1978.3372570189003, 117162.41418792852, 127492.0, 127737.0, 129071.35464322713, 129693.0, 8935.865962010444, 11258.887411125948, 11466.713332166762, 11654.592089277005, 11661.24201926872, 14455.566333010027, 14458.710825783426, 14506.0, 14510.709785804225, 14518.419263229564, 8080.676772929017, 8380.664773408995, 8381.664733410595, 8513.466911231893, 8524.147585241426, 8191.672333106728, 8232.753017409368, 8271.875921861178, 8376.036755773042, 8408.663653453916, 4494.303382975644, 4498.910021799546, 4503.369528266046, 4509.12072145935, 4582.0, 1849.38045754671, 1900.980990190108, 1902.6289873474816, 1918.9136488857755, 1931.8119356595873, 852.7612268564811, 904.9411788233743, 924.2190349154953, 927.3276874266103, 934.9392289501161, 16693.415730449375, 16916.830831691525, 20805.0, 21160.941952902293, 21313.040913158944, 799.5962039170182, 894.4543828264754, 929.656027269901, 944.1738478831023, 958.8226178157063, 9265.907340926638, 10656.627018054485, 10702.357858528514, 11247.943760281147, 11462.885371146182, 571041.0, 583397.0, 590740.0462997657, 598886.0, 602478.0, 102914.0, 130871.0, 132428.0, 137195.6280437203, 140315.29842350722, 939.3424602778083, 954.2652157838426, 978.0561757903515, 979.3878825733916, 980.5930538826307, 18787.34244301443, 19050.333238336592, 19075.236990520778, 19141.521461963563, 19229.230830766614, 52577.7371113142, 53351.59917803786, 53710.92578148493, 55263.15789473665, 55904.72047639736, 78465.19185101862, 79503.60248198723, 79782.6010869942, 79840.0, 81359.5932020336, 5405.945940540544, 6176.66028368444, 7355.521891077063, 7406.333429991111, 7709.9614501927135, 10334.896651033394, 11056.502457389597, 12189.390530473263, 12420.565280215149, 12730.23618582888, 10426.113780328757, 10907.890921091004, 10913.727156821144, 10969.890301096886, 11067.72330691739, 9985.301028927905, 10355.741106472398, 10404.58381664725, 10632.202584806017, 10843.24097313212, 9416.858747118935, 9449.29130315243, 10198.847017294598, 11060.0, 11142.220044596801, 7731.0, 7939.2060793920145, 7985.0, 8204.794880127805, 8222.17778222173, 10203.897961020295, 10854.40300783433, 11053.392063436588, 11177.441127943734, 11197.832032519682], "value": 9288.333578965741, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.9386227619757, 1518.6355274734065, 1537.0700726060732, 1731.402666080202, 1896.819802118799, 7857.4892631978955, 8406.24343809057, 8410.873836892451, 8416.410851240416, 8724.65101395945, 8728.781780455492, 8964.641414343418, 8968.0, 8969.955150224256, 8974.775630609238, 2371.1700904683307, 2397.5564520563667, 2398.07674045492, 2398.940026499334, 2399.076355603088, 2081.396395045435, 2350.6003979323555, 2370.905163793455, 2380.357303528055, 2401.9159329423474, 2126.0857831132607, 2330.4406942333885, 2359.6932398788135, 2369.9407514812096, 2386.510765293119, 2352.658864464643, 2375.881205939696, 2388.749181335952, 2391.5695174868515, 2395.844270122454, 2343.847649902768, 2370.1704403458652, 2373.1456675596773, 2373.905043798246, 2374.9525009499885, 8965.865512017324, 9069.863952040725, 9070.410423322463, 9093.181613654737, 9104.226140778042, 5045.394552653668, 5296.894062118736, 5462.180672899097, 5465.480779325958, 5465.9180112298345, 3694.612065733086, 3791.6966642668795, 3808.3525800613684, 3811.3901775715754, 3816.2749077675153, 1099.5162128663362, 1115.1859142825701, 1127.9492422840992, 1133.218079525125, 1138.049728476723, 11515.76968460626, 11773.352465614387, 11795.29228246308, 11861.0, 12034.037277017906, 4484.753338566345, 4493.168763778712, 4695.835645752382, 4701.153792317347, 4702.95297047032, 9366.812663746823, 9375.953120234355, 9379.765505862271, 9416.858747118798, 9456.432614043179, 5358.866028349323, 5361.169018802092, 5363.597730170253, 5368.5436737876935, 5383.757730902041, 4431.0694754102, 4541.069080838421, 4591.0, 4810.302506136591, 5045.5459008689395, 3740.4202348636004, 3758.981205093957, 3874.578388507183, 3893.6301051400073, 3916.2167566486814, 1162.9941850290695, 1182.4678894497338, 1184.1829137894824, 1192.9045676345872, 1194.8267501212276, 759.4911409355744, 858.7466697324304, 898.4519443139679, 914.8490499067713, 940.7836197674574, 5777.0, 5985.521158307325, 6015.127806468037, 6151.876962460726, 6172.444479996821, 739.9186089530255, 897.9551022448851, 915.6703586708779, 944.0795224655906, 955.2119501411363, 3902.765834049966, 3925.862594809168, 4009.133469569272, 4024.0342317843506, 4025.7383270087353, 10632.734181645521, 10881.727956801144, 11044.33733975964, 11046.447677616086, 11048.337099774039, 9320.487373194539, 10977.725556860983, 10978.890211097787, 11002.394868282321, 11003.889961100447, 7792.84414311722, 8148.67405303793, 8190.672373105128, 8281.792955176168, 8306.626201820933, 5812.82561523155, 5887.470127688529, 5951.036158067914, 6037.547183961308, 6223.719932602953, 9761.365511241747, 9974.950125249326, 10258.384496930208, 10262.127719143957, 10266.384016959006, 10324.43215623148, 10406.791864162826, 10409.635662751767, 10420.895791041992, 10474.68575942723, 7012.123484564429, 7286.453515986216, 7457.179710231979, 7596.08846938371, 7612.543247405173, 8152.144024877302, 8336.958315208385, 8509.531975741145, 8618.138186181332, 8622.741317760478, 7531.096268447603, 7655.770326890202, 7669.1947345527915, 7740.806479838049, 7822.569758663328, 7444.330010299099, 7468.2158373372185, 7469.551826890404, 7610.353119984863, 7616.619169041636, 7071.328223818729, 7205.71177152908, 7208.207097219197, 7208.387287080657, 7211.531250468913, 4970.652054356305, 5130.916691192188, 5145.9228111579105, 5180.948190518197, 5203.375594928481, 4971.154903666313, 4992.625553083606, 5068.619853510928, 5135.820246291362, 5275.973620131875], "value": 4611.046153796995, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.97608191344693, 302.5779038241653, 311.07145171662586, 314.03590975704594, 315.96524382317943, 4757.333973243749, 5298.1058921692575, 5316.5215130638235, 5355.410904800468, 5452.945470545294, 4372.366006928994, 5135.7688903999315, 5189.870253243671, 5291.100512912804, 5325.520703136717, 3178.7615928805376, 4127.607877251671, 4162.625363717265, 4199.685023623225, 4211.620954114129, 3723.478712980185, 4036.9503928978384, 4101.871985204063, 4149.917001659965, 4203.47456567929, 3398.5072164536127, 4078.714489985702, 4092.386142078682, 4097.0371962588915, 4122.443470131524, 4065.3698676705308, 4072.5927407259333, 4076.184763047402, 4113.794310284504, 4116.711830171889, 3568.37553428149, 3577.0878426001204, 3583.7133029357587, 3590.533230679995, 3608.386574282378, 4643.744594047326, 4857.53853383928, 4975.701457912501, 5009.67243680425, 5237.711925844077, 4693.943862630915, 4696.0, 4727.361806156159, 4728.361671174416, 4732.408448943882, 4519.322101684741, 5456.890862182734, 5507.807226747084, 5514.751836167382, 5563.219769673706, 3739.1773809761808, 3909.9218015639813, 3922.8234729437245, 3927.293087244295, 3929.783861887595, 5870.412958704095, 5875.265591801025, 5879.324907167229, 5913.349531551526, 5938.406159384027, 4028.939565906514, 4031.3952907064177, 4040.0, 4049.7570145791346, 4110.712250142521, 3811.866584669523, 4020.43713880057, 4023.195360927826, 4035.3139966205817, 4077.7757223353, 4631.073785242965, 4632.513586073481, 4639.935228823141, 4698.271767875985, 4712.552307530779, 4752.263399173134, 5094.821681241138, 5699.774548472098, 5737.221461346996, 5742.971285143548, 1169.5789515774316, 1295.902807289457, 1327.2368388176644, 1360.3130419138272, 1362.3937347880099, 836.6195776967929, 913.6208473483563, 926.7266156483853, 940.5391358234552, 942.5381563034201, 4152.0, 5200.53943359471, 5224.085784987612, 5225.738713064407, 5265.605079619044, 5156.92264616031, 5160.767765450564, 5276.762545685453, 5401.4868587484125, 5718.828435146952, 4957.82647607332, 5125.4879810456005, 5158.767855446514, 5176.378834539879, 5207.531322181022, 2774.458980498783, 2979.3147576057195, 3042.0, 3043.802152860101, 3055.762416221434, 5359.490848369399, 5746.511546518509, 6012.054093494156, 6086.786962456381, 6113.388661133851, 5224.817131400383, 5559.916601250984, 5767.0, 5895.467178533527, 5952.3154837193415, 5283.2075188722, 6004.789832355847, 6008.459238668541, 6023.602769977941, 6038.6678732670125, 4696.647751418589, 4907.68100073494, 5702.885942281214, 5816.70916454184, 5835.599456130583, 4816.590589799766, 5045.0, 5584.832455026356, 5606.551475882001, 5642.576806739429, 5044.394672639307, 5150.665206761549, 5709.858028394421, 6001.219841420588, 6042.667653279112, 3348.8660453581583, 3649.671529562352, 3866.9806650966566, 3901.7319371203903, 3903.2583809076186, 5534.833954981357, 5740.302058032718, 5812.418758124323, 5816.47651711348, 5835.44563266489, 5539.307586551681, 5571.3871474137, 5581.5255703265675, 5581.6930068846605, 5586.944130558805, 4469.642428605769, 5131.692098474104, 5241.816536421207, 5560.388357280615, 5646.632968857011, 4588.862334129848, 5043.117454445531, 5275.340582427197, 5377.327834020747, 5453.536449401687, 4794.95205047959, 5068.949310506848, 5335.493128152819, 5671.958095085683, 5675.886482270249, 5533.280673512418, 5552.083906155601, 5581.0, 5610.597350662334, 5624.184493248538], "value": 4113.047447806116, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.2412005334372, 757.3146302596149, 790.3124281874766, 793.4445887878486, 797.8484088023272, 14753.557393278206, 14945.626359341022, 15450.845491545082, 15470.149141797197, 15471.69056618867, 14095.71808563828, 14164.433422663107, 14266.785998210034, 14292.142471451694, 14742.0, 834.3908946469066, 856.0540602634093, 856.7601071699903, 856.7943693513542, 858.8754630578562, 672.3411057163983, 691.1705952856568, 697.9127609048869, 850.0946491986045, 854.4531499840107, 730.0254160695478, 731.8828987362023, 883.4610887358738, 919.1406035356929, 922.8846394200725, 911.9908800911973, 914.679862048281, 914.8810654614924, 917.2111983694018, 918.3800934369274, 667.6695035957184, 670.2894931372712, 670.3497607320891, 671.5332843673649, 671.5500614588237, 11942.940285298519, 12387.256764594063, 12415.875841241563, 12437.440315185839, 14426.350814213385, 12170.939145304217, 12897.613071607866, 14066.437342506288, 14088.2955852208, 14150.929245353811, 11609.883901160965, 11743.0, 11746.236494627821, 11800.940995295054, 11937.880621193764, 3732.1789206374824, 3766.679832214265, 3776.6034566370686, 3839.1937693084487, 3850.4224366344993, 102555.4872225634, 104487.43268850974, 104728.0, 106147.0, 106787.0, 10864.78270434587, 10910.290831095954, 10982.835257471144, 11090.279131856403, 11294.37880916541, 21699.915004249724, 21728.609852916168, 21732.0, 21736.673949890763, 21770.455738606663, 7626.084869815658, 7772.18392068836, 7784.41616878725, 7837.09873364559, 8032.236937491046, 4245.766482843474, 4352.238358287287, 4355.825766969349, 4481.5070342262325, 4499.1451624191295, 3425.1094715374184, 3554.608993010767, 3554.928901421957, 3556.78659280444, 3567.8216089195435, 634.6002018728263, 646.2664875366399, 646.5183438338468, 658.5291516565583, 660.8215781738903, 572.2046355565767, 587.2512546503242, 615.3231445410013, 623.8440389902524, 638.4254171245823, 7821.960890195513, 8221.889055472264, 8942.46345219289, 9072.95463522678, 9112.271018318519, 547.5455372041196, 571.9599628026082, 581.8370856160282, 647.4011539326199, 661.1009027722293, 4293.3130699088, 4461.643068554507, 4515.616172625364, 4535.165521549032, 4587.564181402761, 101785.98214017766, 101987.6819708843, 138983.0, 160714.19642901712, 165564.0, 138376.0, 139668.0, 140778.59221407858, 144039.11921761715, 146066.0, 97987.51006244923, 117953.0, 121604.39197803954, 122315.0, 124914.12628810757, 8504.659813607386, 8595.570221489026, 8823.64705411802, 8856.867146992672, 8885.822283554164, 52084.47915520796, 66426.0, 66537.6827896513, 67562.66218668876, 67710.61446927732, 96553.0, 97507.0, 97720.51139744256, 98644.50677746565, 98979.50510247402, 11794.0, 11855.644330670093, 11906.285622862655, 11932.880671193525, 12393.070519710878, 12664.240145591293, 12998.090133690552, 13059.085863989429, 13101.868981310065, 13230.669233269246, 11230.775384492428, 11544.353154156843, 11896.167268291136, 12034.939825300817, 12399.00807935381, 11778.27945818531, 11991.400429978641, 12019.519219231483, 12232.571859984857, 12458.127931044739, 10402.0, 11845.585404511145, 11854.822177667515, 12024.519019239133, 12133.75732485363, 9666.56500457467, 9822.361546499455, 10151.949240254047, 10282.948585257025, 10651.147908167173, 8324.83350333002, 9075.682351117679, 9792.79674537328, 9964.953679863509, 10039.397636141855], "value": 7526.500449588958, "unit": "score"}], "name": "dromaeo_css", "value": 7292.516536870816, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 131.0, 113.0, 114.0, 113.0, 114.0, 113.0, 113.0, 112.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 109.0, 135.0, 162.0, 108.0, 132.0, 131.0, 107.0, 159.0, 108.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 185.0, 186.0, 230.0, 249.0, 182.0, 183.0, 181.0], "value": 194.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 63.0, 77.0, 180.0, 52.0, 81.0, 81.0, 92.0, 154.0, 58.0], "value": 91.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 106.0, 173.0, 100.0, 68.0, 90.0, 121.0, 68.0, 70.0, 121.0], "value": 99.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 84.0, 89.0, 85.0, 88.0, 86.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 148.0, 94.0, 94.0, 93.0, 94.0, 93.0, 95.0, 103.0, 148.0], "value": 109.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 102.0, 135.0, 86.0, 85.0, 86.0, 86.0, 84.0, 86.0, 86.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 52.0, 72.0, 82.0, 83.0, 59.0, 53.0, 54.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 65.0, 78.0, 73.0, 78.0, 70.0, 69.0, 96.0, 104.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 88.0, 85.0, 86.0, 84.0, 85.0, 89.0, 89.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 152.0, 143.0, 135.0, 148.0, 126.0, 150.0, 189.0, 151.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 169.0, 170.0, 171.0, 172.0, 170.0, 170.0, 169.0, 170.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 68.0, 69.0, 67.0, 82.0, 85.0, 84.0], "value": 72.4, "unit": "score"}], "name": "kraken", "value": 1535.9, "alertThreshold": 2.0}]} 06:12:44 INFO - Return code: 0 06:12:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:12:44 INFO - # TBPL SUCCESS # 06:12:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:12:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:12:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:12:44 INFO - 2016-08-31 06:12:44.837 screenresolution[1936:20511] starting screenresolution argv=screenresolution get 06:12:44 INFO - 2016-08-31 06:12:44.840 screenresolution[1936:20511] Display 0: 1600x1200x32@60 06:12:44 INFO - 2016-08-31 06:12:44.845 screenresolution[1937:20515] starting screenresolution argv=screenresolution list 06:12:44 INFO - Available Modes on Display 0 06:12:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:12:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:12:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:12:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:12:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:12:45 INFO - Intel Iris: 06:12:45 INFO - Chipset Model: Intel Iris 06:12:45 INFO - Type: GPU 06:12:45 INFO - Bus: Built-In 06:12:45 INFO - VRAM (Dynamic, Max): 1536 MB 06:12:45 INFO - Vendor: Intel (0x8086) 06:12:45 INFO - Device ID: 0x0a2e 06:12:45 INFO - Revision ID: 0x0009 06:12:45 INFO - Displays: 06:12:45 INFO - Display: 06:12:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:12:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:12:45 INFO - Display Serial Number: 1600x1200 60 06:12:45 INFO - Main Display: Yes 06:12:45 INFO - Mirror: Off 06:12:45 INFO - Online: Yes 06:12:45 INFO - Rotation: Supported 06:12:45 INFO - Return code: 0 06:12:45 INFO - Running post-action listener: _resource_record_post_action 06:12:45 INFO - [mozharness: 2016-08-31 13:12:45.079417Z] Finished run-tests step (success) 06:12:45 INFO - Running post-run listener: _resource_record_post_run 06:12:45 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 69632; Write bytes: 707577856; Read time: 2; Write time: 2013 06:12:45 INFO - TinderboxPrint: CPU usage
53.4% 06:12:45 INFO - TinderboxPrint: I/O read bytes / time
69,632 / 2 06:12:45 INFO - TinderboxPrint: I/O write bytes / time
707,577,856 / 2,013 06:12:45 INFO - TinderboxPrint: CPU idle
2,260.7 (46.6%) 06:12:45 INFO - TinderboxPrint: CPU system
967.4 (19.9%) 06:12:45 INFO - TinderboxPrint: CPU user
1,621.5 (33.4%) 06:12:45 INFO - TinderboxPrint: Swap in / out
405,671,936 / 0 06:12:45 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 390171136; Write bytes: 385157120; Read time: 29561; Write time: 830 06:12:45 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 69632; Write bytes: 298287104; Read time: 2; Write time: 1126 06:12:45 INFO - Running post-run listener: _upload_blobber_files 06:12:45 INFO - Blob upload gear active. 06:12:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:12:45 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:12:45 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:12:45 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:12:45 INFO - (blobuploader) - INFO - Open directory for files ... 06:12:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:12:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:12:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:47 INFO - (blobuploader) - INFO - Done attempting. 06:12:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:12:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:12:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:48 INFO - (blobuploader) - INFO - Done attempting. 06:12:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:12:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:12:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:51 INFO - (blobuploader) - INFO - Done attempting. 06:12:51 INFO - (blobuploader) - INFO - Iteration through files over. 06:12:51 INFO - Return code: 0 06:12:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:12:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:12:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a587bb83613334493ffe71e762f23104a0edc76fc09bba4afa522b3acf94d9676cc5495cbcf2dccf7e40f2bf6ac0acf1f33ca8ede861c62a4b108dfd325d57ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/689e43feda7b49884b25676c3e015a2ad2ab0b317e9c1c89b7f6d4b63ff6845aea2a01bd2c9fa292dedaff269926c694ac0f08ef01caeb6b5d097c0fd4f2b0a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7bde1e36842cf6343033937a0b4235c845f0313f4afbb38a400fcda695b0ca990ae3c23f9c3fa7b26f2669b3793762d26a3a345a8a960237400f409d678b1cc2"} 06:12:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:12:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:12:51 INFO - Contents: 06:12:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a587bb83613334493ffe71e762f23104a0edc76fc09bba4afa522b3acf94d9676cc5495cbcf2dccf7e40f2bf6ac0acf1f33ca8ede861c62a4b108dfd325d57ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/689e43feda7b49884b25676c3e015a2ad2ab0b317e9c1c89b7f6d4b63ff6845aea2a01bd2c9fa292dedaff269926c694ac0f08ef01caeb6b5d097c0fd4f2b0a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7bde1e36842cf6343033937a0b4235c845f0313f4afbb38a400fcda695b0ca990ae3c23f9c3fa7b26f2669b3793762d26a3a345a8a960237400f409d678b1cc2"} 06:12:51 INFO - Running post-run listener: copy_logs_to_upload_dir 06:12:51 INFO - Copying logs to upload dir... 06:12:51 INFO - mkdir: /builds/slave/test/build/upload/logs 06:12:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.060715 ========= master_lag: 2.97 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-31 06:12:54.187450) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 06:12:54.193006) ========= 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.N4Crg1DRPL/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.QE6foDKI1o/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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/a587bb83613334493ffe71e762f23104a0edc76fc09bba4afa522b3acf94d9676cc5495cbcf2dccf7e40f2bf6ac0acf1f33ca8ede861c62a4b108dfd325d57ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/689e43feda7b49884b25676c3e015a2ad2ab0b317e9c1c89b7f6d4b63ff6845aea2a01bd2c9fa292dedaff269926c694ac0f08ef01caeb6b5d097c0fd4f2b0a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7bde1e36842cf6343033937a0b4235c845f0313f4afbb38a400fcda695b0ca990ae3c23f9c3fa7b26f2669b3793762d26a3a345a8a960237400f409d678b1cc2"} build_url:https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011381 build_url: 'https://queue.taskcluster.net/v1/task/IAL08D_ARombBzKLcL8HiA/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/a587bb83613334493ffe71e762f23104a0edc76fc09bba4afa522b3acf94d9676cc5495cbcf2dccf7e40f2bf6ac0acf1f33ca8ede861c62a4b108dfd325d57ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/689e43feda7b49884b25676c3e015a2ad2ab0b317e9c1c89b7f6d4b63ff6845aea2a01bd2c9fa292dedaff269926c694ac0f08ef01caeb6b5d097c0fd4f2b0a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7bde1e36842cf6343033937a0b4235c845f0313f4afbb38a400fcda695b0ca990ae3c23f9c3fa7b26f2669b3793762d26a3a345a8a960237400f409d678b1cc2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 06:12:54.226937) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 06:12:54.227252) ========= 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.N4Crg1DRPL/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.QE6foDKI1o/Listeners TMPDIR=/var/folders/38/7s9s8qps6rj1x54mtxs_b9vw00000w/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.005469 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 06:12:54.275113) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 06:12:54.275406) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 06:12:54.275716) ========= ========= Total master_lag: 3.11 =========