builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-062 starttime: 1482456006.88 results: success (0) buildid: 20161222171947 builduid: f2f1213104b74afeb090c15fe7c382af revision: 698b5719c9ee8cefe0a894c37c1ef865e34d0769 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:06.875879) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:06.876276) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:06.876755) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:06.944665) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:06.944937) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-22 17:20:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-12-22 17:20:07 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151650 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:07.113048) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:07.113398) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016366 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:07.163125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:07.163488) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 698b5719c9ee8cefe0a894c37c1ef865e34d0769 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 698b5719c9ee8cefe0a894c37c1ef865e34d0769 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-22 17:20:07,536 truncating revision to first 12 chars 2016-12-22 17:20:07,536 Setting DEBUG logging. 2016-12-22 17:20:07,536 attempt 1/10 2016-12-22 17:20:07,536 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/698b5719c9ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 17:20:07,883 unpacking tar archive at: mozilla-inbound-698b5719c9ee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.668641 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:07.845191) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:07.845475) ========= script_repo_revision: 698b5719c9ee8cefe0a894c37c1ef865e34d0769 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:07.845848) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:07.846110) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 17:20:07.863231) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-22 17:20:07.863582) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:20:08 INFO - MultiFileLogger online at 20161222 17:20:08 in /builds/slave/test 17:20:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 17:20:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:20:08 INFO - {'append_to_log': False, 17:20:08 INFO - 'base_work_dir': '/builds/slave/test', 17:20:08 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 17:20:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:20:08 INFO - 'branch': 'Mozilla-Inbound', 17:20:08 INFO - 'buildbot_json_path': 'buildprops.json', 17:20:08 INFO - 'config_files': ('talos/linux_config.py',), 17:20:08 INFO - 'default_actions': ('clobber', 17:20:08 INFO - 'read-buildbot-config', 17:20:08 INFO - 'download-and-extract', 17:20:08 INFO - 'populate-webroot', 17:20:08 INFO - 'create-virtualenv', 17:20:08 INFO - 'install', 17:20:08 INFO - 'run-tests'), 17:20:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:20:08 INFO - 'download_minidump_stackwalk': True, 17:20:08 INFO - 'download_symbols': 'ondemand', 17:20:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:20:08 INFO - 'tooltool.py': '/tools/tooltool.py', 17:20:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:20:08 INFO - '/tools/misc-python/virtualenv.py')}, 17:20:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:20:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:20:08 INFO - 'installer_path': 'installer.exe', 17:20:08 INFO - 'log_level': 'info', 17:20:08 INFO - 'log_name': 'talos', 17:20:08 INFO - 'log_to_console': True, 17:20:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:20:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:20:08 INFO - 'opt_config_files': (), 17:20:08 INFO - 'pip_index': False, 17:20:08 INFO - 'sps_profile': False, 17:20:08 INFO - 'sps_profile_interval': 0, 17:20:08 INFO - 'suite': 'dromaeojs', 17:20:08 INFO - 'system_bits': '32', 17:20:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:20:08 INFO - 'title': 'talos-linux64-ix-062', 17:20:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:20:08 INFO - 'use_talos_json': True, 17:20:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:20:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:20:08 INFO - 'work_dir': 'build'} 17:20:08 INFO - [mozharness: 2016-12-23 01:20:08.338258Z] Running clobber step. 17:20:08 INFO - Running pre-action listener: _resource_record_pre_action 17:20:08 INFO - Running main action method: clobber 17:20:08 INFO - rmtree: /builds/slave/test/build 17:20:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:20:09 INFO - Running post-action listener: _resource_record_post_action 17:20:09 INFO - [mozharness: 2016-12-23 01:20:09.323178Z] Finished clobber step (success) 17:20:09 INFO - [mozharness: 2016-12-23 01:20:09.323279Z] Running read-buildbot-config step. 17:20:09 INFO - Running pre-action listener: _resource_record_pre_action 17:20:09 INFO - Running main action method: read_buildbot_config 17:20:09 INFO - Using buildbot properties: 17:20:09 INFO - { 17:20:09 INFO - "project": "", 17:20:09 INFO - "product": "firefox", 17:20:09 INFO - "script_repo_revision": "production", 17:20:09 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 17:20:09 INFO - "repository": "", 17:20:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 17:20:09 INFO - "buildid": "20161222171947", 17:20:09 INFO - "stage_platform": "linux64-pgo", 17:20:09 INFO - "basedir": "/builds/slave/test", 17:20:09 INFO - "buildnumber": 3107, 17:20:09 INFO - "platform": "ubuntu64_hw", 17:20:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:20:09 INFO - "slavebuilddir": "test", 17:20:09 INFO - "branch": "mozilla-inbound", 17:20:09 INFO - "revision": "698b5719c9ee8cefe0a894c37c1ef865e34d0769", 17:20:09 INFO - "slavename": "talos-linux64-ix-062", 17:20:09 INFO - "builduid": "f2f1213104b74afeb090c15fe7c382af", 17:20:09 INFO - "repo_path": "integration/mozilla-inbound" 17:20:09 INFO - } 17:20:09 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 17:20:09 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 17:20:09 INFO - Running post-action listener: _resource_record_post_action 17:20:09 INFO - [mozharness: 2016-12-23 01:20:09.325650Z] Finished read-buildbot-config step (success) 17:20:09 INFO - [mozharness: 2016-12-23 01:20:09.325740Z] Running download-and-extract step. 17:20:09 INFO - Running pre-action listener: _resource_record_pre_action 17:20:09 INFO - Running main action method: download_and_extract 17:20:09 INFO - mkdir: /builds/slave/test/build/tests 17:20:09 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')]} 17:20:09 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:20:09 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:20:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:20:09 INFO - Downloaded 1361 bytes. 17:20:09 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:20:09 INFO - Using the following test package requirements: 17:20:09 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 17:20:09 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:20:09 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:20:09 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:09 INFO - u'jsshell-linux-x86_64.zip'], 17:20:09 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:20:09 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 17:20:09 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:20:09 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:20:09 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:20:09 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:20:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:20:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 17:20:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:20:09 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:20:09 INFO - Expected file size: 21826799 17:20:09 INFO - Obtained file size: 21826799 17:20:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:20:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 17:20:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:20:13 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:20:14 INFO - Expected file size: 21826799 17:20:14 INFO - Obtained file size: 21826799 17:20:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 17:20:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:20:18 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 17:20:18 INFO - Expected file size: 14409776 17:20:18 INFO - Obtained file size: 14409776 17:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:18 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:20:18 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:20:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:20:20 INFO - Downloaded 61384427 bytes. 17:20:20 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:20:20 INFO - mkdir: /builds/slave/test/properties 17:20:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:20:20 INFO - Writing to file /builds/slave/test/properties/build_url 17:20:20 INFO - Contents: 17:20:20 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:20:20 INFO - Running post-action listener: _resource_record_post_action 17:20:20 INFO - Running post-action listener: set_extra_try_arguments 17:20:20 INFO - [mozharness: 2016-12-23 01:20:20.798590Z] Finished download-and-extract step (success) 17:20:20 INFO - [mozharness: 2016-12-23 01:20:20.798818Z] Running populate-webroot step. 17:20:20 INFO - Running pre-action listener: _resource_record_pre_action 17:20:20 INFO - Running main action method: populate_webroot 17:20:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:20:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:20:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:20:20 INFO - '--tppagecycles', 17:20:20 INFO - '7'], 17:20:20 INFO - 'tests': ['tsvgm']}}, 17:20:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:20:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:20:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:20:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:20:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:20:20 INFO - 'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:20:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:20:20 INFO - 'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['damp', 'tps']}, 17:20:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:20:20 INFO - 'tests': ['damp', 'tps']}, 17:20:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['dromaeo_dom']}, 17:20:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:20:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['basic_compositor_video']}, 17:20:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:20:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['a11yr', 17:20:20 INFO - 'ts_paint', 17:20:20 INFO - 'tpaint', 17:20:20 INFO - 'sessionrestore', 17:20:20 INFO - 'sessionrestore_no_auto_restore', 17:20:20 INFO - 'tabpaint']}, 17:20:20 INFO - 'other-e10s': {'tests': ['a11yr', 17:20:20 INFO - 'ts_paint', 17:20:20 INFO - 'tpaint', 17:20:20 INFO - 'sessionrestore', 17:20:20 INFO - 'sessionrestore_no_auto_restore', 17:20:20 INFO - 'tabpaint']}, 17:20:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:20:20 INFO - 'ts_paint', 17:20:20 INFO - 'tpaint', 17:20:20 INFO - 'sessionrestore', 17:20:20 INFO - 'sessionrestore_no_auto_restore', 17:20:20 INFO - 'tabpaint']}, 17:20:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:20:20 INFO - 'ts_paint', 17:20:20 INFO - 'tpaint', 17:20:20 INFO - 'sessionrestore', 17:20:20 INFO - 'sessionrestore_no_auto_restore', 17:20:20 INFO - 'tabpaint']}, 17:20:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['a11yr', 17:20:20 INFO - 'ts_paint', 17:20:20 INFO - 'tpaint', 17:20:20 INFO - 'sessionrestore', 17:20:20 INFO - 'sessionrestore_no_auto_restore', 17:20:20 INFO - 'tabpaint']}, 17:20:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['a11yr', 17:20:20 INFO - 'ts_paint', 17:20:20 INFO - 'tpaint', 17:20:20 INFO - 'sessionrestore', 17:20:20 INFO - 'sessionrestore_no_auto_restore', 17:20:20 INFO - 'tabpaint']}, 17:20:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['tsvgx', 17:20:20 INFO - 'tsvgr_opacity', 17:20:20 INFO - 'tart', 17:20:20 INFO - 'tscrollx', 17:20:20 INFO - 'cart', 17:20:20 INFO - 'tsvg_static']}, 17:20:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:20:20 INFO - 'tsvgr_opacity', 17:20:20 INFO - 'tart', 17:20:20 INFO - 'tscrollx', 17:20:20 INFO - 'cart', 17:20:20 INFO - 'tsvg_static']}, 17:20:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:20:20 INFO - 'talos_options': ['--disable-e10s'], 17:20:20 INFO - 'tests': ['tp5o']}, 17:20:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:20:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:20:20 INFO - 'talos_options': ['--disable-e10s', 17:20:20 INFO - '--xperf_path', 17:20:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:20 INFO - 'tests': ['tp5n']}, 17:20:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:20:20 INFO - 'talos_options': ['--xperf_path', 17:20:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:20 INFO - 'tests': ['tp5n']}}, 17:20:20 INFO - 'talos.zip': {'path': '', 17:20:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:20:20 INFO - Running post-action listener: _resource_record_post_action 17:20:20 INFO - [mozharness: 2016-12-23 01:20:20.819718Z] Finished populate-webroot step (success) 17:20:20 INFO - [mozharness: 2016-12-23 01:20:20.819807Z] Running create-virtualenv step. 17:20:20 INFO - Running pre-action listener: _resource_record_pre_action 17:20:20 INFO - Running main action method: create_virtualenv 17:20:20 INFO - Creating virtualenv /builds/slave/test/build/venv 17:20:20 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 17:20:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:20:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:20:20 INFO - Using real prefix '/usr' 17:20:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:20:21 INFO - Installing distribute.............................................................................................................................................................................................done. 17:20:23 INFO - Installing pip.................done. 17:20:23 INFO - Return code: 0 17:20:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:20:23 INFO - Reading from file tmpfile_stdout 17:20:23 INFO - Output received: 17:20:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:20:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:20:23 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')]} 17:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:23 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')]} 17:20:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:23 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 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20:23 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 17:20:23 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 17:20:23 INFO - Using env: {'DISPLAY': ':0', 17:20:23 INFO - 'HOME': '/home/cltbld', 17:20:23 INFO - 'LANG': 'en_US.UTF-8', 17:20:23 INFO - 'LANGUAGE': 'en_US:en', 17:20:23 INFO - 'LOGNAME': 'cltbld', 17:20:23 INFO - 'MAIL': '/var/mail/cltbld', 17:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:23 INFO - 'MOZ_NO_REMOTE': '1', 17:20:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:23 INFO - 'NO_EM_RESTART': '1', 17:20:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:23 INFO - 'PWD': '/builds/slave/test', 17:20:23 INFO - 'SHELL': '/bin/bash', 17:20:23 INFO - 'SHLVL': '1', 17:20:23 INFO - 'TERM': 'linux', 17:20:23 INFO - 'TMOUT': '86400', 17:20:23 INFO - 'USER': 'cltbld', 17:20:23 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:23 INFO - '_': '/tools/buildbot/bin/python'} 17:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:23 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 17:20:23 INFO - Cleaning up... 17:20:23 INFO - Return code: 0 17:20:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:20:23 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')]} 17:20:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:23 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')]} 17:20:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:23 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 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20:23 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 17:20:23 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 17:20:23 INFO - Using env: {'DISPLAY': ':0', 17:20:23 INFO - 'HOME': '/home/cltbld', 17:20:23 INFO - 'LANG': 'en_US.UTF-8', 17:20:23 INFO - 'LANGUAGE': 'en_US:en', 17:20:23 INFO - 'LOGNAME': 'cltbld', 17:20:23 INFO - 'MAIL': '/var/mail/cltbld', 17:20:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:23 INFO - 'MOZ_NO_REMOTE': '1', 17:20:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:23 INFO - 'NO_EM_RESTART': '1', 17:20:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:23 INFO - 'PWD': '/builds/slave/test', 17:20:23 INFO - 'SHELL': '/bin/bash', 17:20:23 INFO - 'SHLVL': '1', 17:20:23 INFO - 'TERM': 'linux', 17:20:23 INFO - 'TMOUT': '86400', 17:20:23 INFO - 'USER': 'cltbld', 17:20:23 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:23 INFO - '_': '/tools/buildbot/bin/python'} 17:20:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:24 INFO - Downloading/unpacking psutil>=3.1.1 17:20:24 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 17:20:24 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 17:20:24 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 17:20:24 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 17:20:24 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 17:20:24 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 17:20:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:20:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:26 INFO - Installing collected packages: psutil 17:20:26 INFO - Running setup.py install for psutil 17:20:26 INFO - building 'psutil._psutil_linux' extension 17:20:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:20:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:20:26 INFO - building 'psutil._psutil_posix' extension 17:20:26 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:20:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:20:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:26 INFO - Successfully installed psutil 17:20:26 INFO - Cleaning up... 17:20:26 INFO - Return code: 0 17:20:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:20: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')]} 17:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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')]} 17:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:26 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 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20:26 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 17:20:26 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 17:20:26 INFO - Using env: {'DISPLAY': ':0', 17:20:26 INFO - 'HOME': '/home/cltbld', 17:20:26 INFO - 'LANG': 'en_US.UTF-8', 17:20:26 INFO - 'LANGUAGE': 'en_US:en', 17:20:26 INFO - 'LOGNAME': 'cltbld', 17:20:26 INFO - 'MAIL': '/var/mail/cltbld', 17:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:26 INFO - 'MOZ_NO_REMOTE': '1', 17:20:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:26 INFO - 'NO_EM_RESTART': '1', 17:20:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:26 INFO - 'PWD': '/builds/slave/test', 17:20:26 INFO - 'SHELL': '/bin/bash', 17:20:26 INFO - 'SHLVL': '1', 17:20:26 INFO - 'TERM': 'linux', 17:20:26 INFO - 'TMOUT': '86400', 17:20:26 INFO - 'USER': 'cltbld', 17:20:26 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:26 INFO - '_': '/tools/buildbot/bin/python'} 17:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:20:26 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 17:20:26 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 17:20:26 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 17:20:26 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 17:20:26 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 17:20:26 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 17:20:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:20:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:20:29 INFO - Installing collected packages: mozsystemmonitor 17:20:29 INFO - Running setup.py install for mozsystemmonitor 17:20:29 INFO - Successfully installed mozsystemmonitor 17:20:29 INFO - Cleaning up... 17:20:29 INFO - Return code: 0 17:20:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:29 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 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20:29 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 17:20:29 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 17:20:29 INFO - Using env: {'DISPLAY': ':0', 17:20:29 INFO - 'HOME': '/home/cltbld', 17:20:29 INFO - 'LANG': 'en_US.UTF-8', 17:20:29 INFO - 'LANGUAGE': 'en_US:en', 17:20:29 INFO - 'LOGNAME': 'cltbld', 17:20:29 INFO - 'MAIL': '/var/mail/cltbld', 17:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:29 INFO - 'MOZ_NO_REMOTE': '1', 17:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:29 INFO - 'NO_EM_RESTART': '1', 17:20:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:29 INFO - 'PWD': '/builds/slave/test', 17:20:29 INFO - 'SHELL': '/bin/bash', 17:20:29 INFO - 'SHLVL': '1', 17:20:29 INFO - 'TERM': 'linux', 17:20:29 INFO - 'TMOUT': '86400', 17:20:29 INFO - 'USER': 'cltbld', 17:20:29 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:29 INFO - '_': '/tools/buildbot/bin/python'} 17:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:29 INFO - Downloading/unpacking jsonschema==2.5.1 17:20:29 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 17:20:29 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 17:20:29 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 17:20:29 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 17:20:29 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 17:20:29 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 17:20:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:20:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:31 INFO - Downloading functools32-3.2.3-2.tar.gz 17:20:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:20:31 INFO - warning: no files found matching '*.txt' 17:20:31 INFO - no previously-included directories found matching 'build' 17:20:31 INFO - no previously-included directories found matching 'dist' 17:20:31 INFO - no previously-included directories found matching '.git*' 17:20:31 INFO - Installing collected packages: jsonschema, functools32 17:20:31 INFO - Running setup.py install for functools32 17:20:31 INFO - warning: no files found matching '*.txt' 17:20:31 INFO - no previously-included directories found matching 'build' 17:20:31 INFO - no previously-included directories found matching 'dist' 17:20:31 INFO - no previously-included directories found matching '.git*' 17:20:31 INFO - Successfully installed jsonschema functools32 17:20:31 INFO - Cleaning up... 17:20:31 INFO - Return code: 0 17:20:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:20: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')]} 17:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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')]} 17:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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', '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 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:20: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 functools32==3.2.3-2 17:20:31 INFO - Using env: {'DISPLAY': ':0', 17:20:31 INFO - 'HOME': '/home/cltbld', 17:20:31 INFO - 'LANG': 'en_US.UTF-8', 17:20:31 INFO - 'LANGUAGE': 'en_US:en', 17:20:31 INFO - 'LOGNAME': 'cltbld', 17:20:31 INFO - 'MAIL': '/var/mail/cltbld', 17:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:31 INFO - 'MOZ_NO_REMOTE': '1', 17:20:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:31 INFO - 'NO_EM_RESTART': '1', 17:20:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:31 INFO - 'PWD': '/builds/slave/test', 17:20:31 INFO - 'SHELL': '/bin/bash', 17:20:31 INFO - 'SHLVL': '1', 17:20:31 INFO - 'TERM': 'linux', 17:20:31 INFO - 'TMOUT': '86400', 17:20:31 INFO - 'USER': 'cltbld', 17:20:31 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:31 INFO - '_': '/tools/buildbot/bin/python'} 17:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:20:32 INFO - Cleaning up... 17:20:32 INFO - Return code: 0 17:20:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:20:32 INFO - Using env: {'DISPLAY': ':0', 17:20:32 INFO - 'HOME': '/home/cltbld', 17:20:32 INFO - 'LANG': 'en_US.UTF-8', 17:20:32 INFO - 'LANGUAGE': 'en_US:en', 17:20:32 INFO - 'LOGNAME': 'cltbld', 17:20:32 INFO - 'MAIL': '/var/mail/cltbld', 17:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:32 INFO - 'MOZ_NO_REMOTE': '1', 17:20:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:32 INFO - 'NO_EM_RESTART': '1', 17:20:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:32 INFO - 'PWD': '/builds/slave/test', 17:20:32 INFO - 'SHELL': '/bin/bash', 17:20:32 INFO - 'SHLVL': '1', 17:20:32 INFO - 'TERM': 'linux', 17:20:32 INFO - 'TMOUT': '86400', 17:20:32 INFO - 'USER': 'cltbld', 17:20:32 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:32 INFO - '_': '/tools/buildbot/bin/python'} 17:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:32 INFO - Downloading/unpacking blobuploader==1.2.4 17:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:34 INFO - Downloading blobuploader-1.2.4.tar.gz 17:20:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:20:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:20:34 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 17:20:34 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 17:20:34 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 17:20:34 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 17:20:34 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 17:20:34 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 17:20:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:20:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:20:34 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 17:20:34 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 17:20:34 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 17:20:34 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 17:20:34 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 17:20:34 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 17:20:35 INFO - Downloading docopt-0.6.1.tar.gz 17:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:20:35 INFO - Installing collected packages: blobuploader, requests, docopt 17:20:35 INFO - Running setup.py install for blobuploader 17:20:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:20:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:20:35 INFO - Running setup.py install for requests 17:20:35 INFO - Running setup.py install for docopt 17:20:35 INFO - Successfully installed blobuploader requests docopt 17:20:35 INFO - Cleaning up... 17:20:35 INFO - Return code: 0 17:20:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:35 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 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20:35 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 17:20:35 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 17:20:35 INFO - Using env: {'DISPLAY': ':0', 17:20:35 INFO - 'HOME': '/home/cltbld', 17:20:35 INFO - 'LANG': 'en_US.UTF-8', 17:20:35 INFO - 'LANGUAGE': 'en_US:en', 17:20:35 INFO - 'LOGNAME': 'cltbld', 17:20:35 INFO - 'MAIL': '/var/mail/cltbld', 17:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:35 INFO - 'MOZ_NO_REMOTE': '1', 17:20:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:35 INFO - 'NO_EM_RESTART': '1', 17:20:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:35 INFO - 'PWD': '/builds/slave/test', 17:20:35 INFO - 'SHELL': '/bin/bash', 17:20:35 INFO - 'SHLVL': '1', 17:20:35 INFO - 'TERM': 'linux', 17:20:35 INFO - 'TMOUT': '86400', 17:20:35 INFO - 'USER': 'cltbld', 17:20:35 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:35 INFO - '_': '/tools/buildbot/bin/python'} 17:20:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:20:35 INFO - Running setup.py (path:/tmp/pip-rOiOUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:20:36 INFO - Running setup.py (path:/tmp/pip-hK5sCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:36 INFO - Running setup.py (path:/tmp/pip-ck4c3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:36 INFO - Running setup.py (path:/tmp/pip-O9NmQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:36 INFO - Running setup.py (path:/tmp/pip-ZpW1aA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:36 INFO - Running setup.py (path:/tmp/pip-CNMqki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:36 INFO - Running setup.py (path:/tmp/pip-ebNNrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:36 INFO - Running setup.py (path:/tmp/pip-YhlSxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:36 INFO - Running setup.py (path:/tmp/pip-Gx2pUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:36 INFO - Running setup.py (path:/tmp/pip-_5afbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:36 INFO - Running setup.py (path:/tmp/pip-GZN5nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:36 INFO - Running setup.py (path:/tmp/pip-2F8L7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:36 INFO - Running setup.py (path:/tmp/pip-ZYgcWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:36 INFO - Running setup.py (path:/tmp/pip-FdK96S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:36 INFO - Running setup.py (path:/tmp/pip-SWrVdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:37 INFO - Running setup.py (path:/tmp/pip-QWXC7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:37 INFO - Running setup.py (path:/tmp/pip-nWsrra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:20:37 INFO - Running setup.py install for manifestparser 17:20:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:20:37 INFO - Running setup.py install for mozcrash 17:20:37 INFO - Running setup.py install for mozdebug 17:20:37 INFO - Running setup.py install for mozdevice 17:20:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:20:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:20:37 INFO - Running setup.py install for mozfile 17:20:37 INFO - Running setup.py install for mozhttpd 17:20:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:20:37 INFO - Running setup.py install for mozinfo 17:20:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:20:37 INFO - Running setup.py install for mozInstall 17:20:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:20:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:20:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:20:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:20:37 INFO - Running setup.py install for mozleak 17:20:38 INFO - Running setup.py install for mozlog 17:20:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:20:38 INFO - Running setup.py install for moznetwork 17:20:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:20:38 INFO - Running setup.py install for mozprocess 17:20:38 INFO - Running setup.py install for mozprofile 17:20:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:20:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:20:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:20:38 INFO - Running setup.py install for mozrunner 17:20:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:20:38 INFO - Running setup.py install for mozscreenshot 17:20:38 INFO - Running setup.py install for moztest 17:20:38 INFO - Running setup.py install for mozversion 17:20:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:20:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:20:38 INFO - Cleaning up... 17:20:39 INFO - Return code: 0 17:20:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:39 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 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20:39 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 17:20:39 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 17:20:39 INFO - Using env: {'DISPLAY': ':0', 17:20:39 INFO - 'HOME': '/home/cltbld', 17:20:39 INFO - 'LANG': 'en_US.UTF-8', 17:20:39 INFO - 'LANGUAGE': 'en_US:en', 17:20:39 INFO - 'LOGNAME': 'cltbld', 17:20:39 INFO - 'MAIL': '/var/mail/cltbld', 17:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:39 INFO - 'MOZ_NO_REMOTE': '1', 17:20:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:39 INFO - 'NO_EM_RESTART': '1', 17:20:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:39 INFO - 'PWD': '/builds/slave/test', 17:20:39 INFO - 'SHELL': '/bin/bash', 17:20:39 INFO - 'SHLVL': '1', 17:20:39 INFO - 'TERM': 'linux', 17:20:39 INFO - 'TMOUT': '86400', 17:20:39 INFO - 'USER': 'cltbld', 17:20:39 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:39 INFO - '_': '/tools/buildbot/bin/python'} 17:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:20:39 INFO - Running setup.py (path:/tmp/pip-Ve0L_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:20:39 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:20:39 INFO - Running setup.py (path:/tmp/pip-9cQEJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:39 INFO - Running setup.py (path:/tmp/pip-px91cR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:39 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:39 INFO - Running setup.py (path:/tmp/pip-UhJtMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:39 INFO - Running setup.py (path:/tmp/pip-jqJFVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:39 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:39 INFO - Running setup.py (path:/tmp/pip-mFeOCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:39 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:39 INFO - Running setup.py (path:/tmp/pip-ySLkuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:39 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:39 INFO - Running setup.py (path:/tmp/pip-z0Ze5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:39 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:39 INFO - Running setup.py (path:/tmp/pip-wkv3Zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:39 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:39 INFO - Running setup.py (path:/tmp/pip-gkpu6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 17:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:39 INFO - Running setup.py (path:/tmp/pip-KG9Z_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:40 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)) 17:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:40 INFO - Running setup.py (path:/tmp/pip-f1HJ4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:40 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)) 17:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:40 INFO - Running setup.py (path:/tmp/pip-aUplXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:40 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)) 17:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:40 INFO - Running setup.py (path:/tmp/pip-wXNutH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 17:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:40 INFO - Running setup.py (path:/tmp/pip-JduJzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:40 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)) 17:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:40 INFO - Running setup.py (path:/tmp/pip-GvXKhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:40 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)) 17:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:40 INFO - Running setup.py (path:/tmp/pip-c3wfco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:40 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)) 17:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:20:40 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)) 17:20:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:20:40 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:20:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:20:40 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 17:20:40 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 17:20:40 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 17:20:40 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 17:20:40 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 17:20:40 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 17:20:42 INFO - Downloading blessings-1.6.tar.gz 17:20:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:20:42 INFO - Installing collected packages: blessings 17:20:42 INFO - Running setup.py install for blessings 17:20:42 INFO - Successfully installed blessings 17:20:42 INFO - Cleaning up... 17:20:42 INFO - Return code: 0 17:20:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:20:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:42 INFO - Reading from file tmpfile_stdout 17:20:42 INFO - Current package versions: 17:20:42 INFO - argparse == 1.2.1 17:20:42 INFO - blessings == 1.6 17:20:42 INFO - blobuploader == 1.2.4 17:20:42 INFO - docopt == 0.6.1 17:20:42 INFO - functools32 == 3.2.3-2 17:20:42 INFO - jsonschema == 2.5.1 17:20:42 INFO - manifestparser == 1.1 17:20:42 INFO - mozInstall == 1.12 17:20:42 INFO - mozcrash == 1.0 17:20:42 INFO - mozdebug == 0.1 17:20:42 INFO - mozdevice == 0.49 17:20:42 INFO - mozfile == 1.2 17:20:42 INFO - mozhttpd == 0.7 17:20:42 INFO - mozinfo == 0.9 17:20:42 INFO - mozleak == 0.1 17:20:42 INFO - mozlog == 3.4 17:20:42 INFO - moznetwork == 0.27 17:20:42 INFO - mozprocess == 0.23 17:20:42 INFO - mozprofile == 0.28 17:20:42 INFO - mozrunner == 6.13 17:20:42 INFO - mozscreenshot == 0.1 17:20:42 INFO - mozsystemmonitor == 0.3 17:20:42 INFO - moztest == 0.7 17:20:42 INFO - mozversion == 1.4 17:20:42 INFO - psutil == 3.1.1 17:20:42 INFO - requests == 1.2.3 17:20:42 INFO - wsgiref == 0.1.2 17:20:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:42 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 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20:42 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 17:20:42 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 17:20:42 INFO - Using env: {'DISPLAY': ':0', 17:20:42 INFO - 'HOME': '/home/cltbld', 17:20:42 INFO - 'LANG': 'en_US.UTF-8', 17:20:42 INFO - 'LANGUAGE': 'en_US:en', 17:20:42 INFO - 'LOGNAME': 'cltbld', 17:20:42 INFO - 'MAIL': '/var/mail/cltbld', 17:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:42 INFO - 'MOZ_NO_REMOTE': '1', 17:20:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:42 INFO - 'NO_EM_RESTART': '1', 17:20:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:42 INFO - 'PWD': '/builds/slave/test', 17:20:42 INFO - 'SHELL': '/bin/bash', 17:20:42 INFO - 'SHLVL': '1', 17:20:42 INFO - 'TERM': 'linux', 17:20:42 INFO - 'TMOUT': '86400', 17:20:42 INFO - 'USER': 'cltbld', 17:20:42 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:42 INFO - '_': '/tools/buildbot/bin/python'} 17:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:43 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)) 17:20:43 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)) 17:20:43 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)) 17:20:43 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)) 17:20:43 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)) 17:20:43 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)) 17:20:43 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)) 17:20:43 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)) 17:20:43 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)) 17:20:43 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)) 17:20:43 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)) 17:20:43 INFO - Cleaning up... 17:20:43 INFO - Return code: 0 17:20:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:20:43 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')]} 17:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:43 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')]} 17:20:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:43 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 0xd181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe28a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108a630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:20:43 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 17:20:43 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 17:20:43 INFO - Using env: {'DISPLAY': ':0', 17:20:43 INFO - 'HOME': '/home/cltbld', 17:20:43 INFO - 'LANG': 'en_US.UTF-8', 17:20:43 INFO - 'LANGUAGE': 'en_US:en', 17:20:43 INFO - 'LOGNAME': 'cltbld', 17:20:43 INFO - 'MAIL': '/var/mail/cltbld', 17:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:43 INFO - 'MOZ_NO_REMOTE': '1', 17:20:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:43 INFO - 'NO_EM_RESTART': '1', 17:20:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:43 INFO - 'PWD': '/builds/slave/test', 17:20:43 INFO - 'SHELL': '/bin/bash', 17:20:43 INFO - 'SHLVL': '1', 17:20:43 INFO - 'TERM': 'linux', 17:20:43 INFO - 'TMOUT': '86400', 17:20:43 INFO - 'USER': 'cltbld', 17:20:43 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:43 INFO - '_': '/tools/buildbot/bin/python'} 17:20:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:20:43 INFO - Cleaning up... 17:20:43 INFO - Return code: 0 17:20:43 INFO - Running post-action listener: _resource_record_post_action 17:20:43 INFO - Running post-action listener: _start_resource_monitoring 17:20:43 INFO - Starting resource monitoring. 17:20:43 INFO - [mozharness: 2016-12-23 01:20:43.447091Z] Finished create-virtualenv step (success) 17:20:43 INFO - [mozharness: 2016-12-23 01:20:43.447824Z] Running install step. 17:20:43 INFO - Running pre-action listener: _resource_record_pre_action 17:20:43 INFO - Running main action method: install 17:20:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:43 INFO - Reading from file tmpfile_stdout 17:20:43 INFO - Detecting whether we're running mozinstall >=1.0... 17:20:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:20:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:20:43 INFO - Reading from file tmpfile_stdout 17:20:43 INFO - Output received: 17:20:43 INFO - Usage: mozinstall [options] installer 17:20:43 INFO - Options: 17:20:43 INFO - -h, --help show this help message and exit 17:20:43 INFO - -d DEST, --destination=DEST 17:20:43 INFO - Directory to install application into. [default: 17:20:43 INFO - "/builds/slave/test"] 17:20:43 INFO - --app=APP Application being installed. [default: firefox] 17:20:43 INFO - mkdir: /builds/slave/test/build/application 17:20:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:20:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:21:01 INFO - Reading from file tmpfile_stdout 17:21:01 INFO - Output received: 17:21:01 INFO - /builds/slave/test/build/application/firefox/firefox 17:21:01 INFO - Running post-action listener: _resource_record_post_action 17:21:01 INFO - [mozharness: 2016-12-23 01:21:01.116301Z] Finished install step (success) 17:21:01 INFO - [mozharness: 2016-12-23 01:21:01.116576Z] Running run-tests step. 17:21:01 INFO - Running pre-action listener: _resource_record_pre_action 17:21:01 INFO - Running main action method: run_tests 17:21:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:21:01 INFO - Python 2.7.3 17:21:01 INFO - Return code: 0 17:21:01 INFO - grabbing minidump binary from tooltool 17:21:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:21:01 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/linux64/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 0x1113850>, '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 0x10886d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1088b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:21:01 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:21:01 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/linux64/releng.manifest -o -c /builds/tooltool_cache 17:21:01 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:21:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:21:01 INFO - Return code: 0 17:21:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:21:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:21:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:21:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:21:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:21:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 17:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 17:21:01 INFO - Using env: {'DISPLAY': ':0', 17:21:01 INFO - 'HOME': '/home/cltbld', 17:21:01 INFO - 'LANG': 'en_US.UTF-8', 17:21:01 INFO - 'LANGUAGE': 'en_US:en', 17:21:01 INFO - 'LOGNAME': 'cltbld', 17:21:01 INFO - 'MAIL': '/var/mail/cltbld', 17:21:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:21:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:01 INFO - 'MOZ_NO_REMOTE': '1', 17:21:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:21:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:01 INFO - 'NO_EM_RESTART': '1', 17:21:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:21:01 INFO - 'PWD': '/builds/slave/test', 17:21:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:21:01 INFO - 'SHELL': '/bin/bash', 17:21:01 INFO - 'SHLVL': '1', 17:21:01 INFO - 'TERM': 'linux', 17:21:01 INFO - 'TMOUT': '86400', 17:21:01 INFO - 'USER': 'cltbld', 17:21:01 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583', 17:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:01 INFO - '_': '/tools/buildbot/bin/python'} 17:21:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 17:21:01 INFO - mozversion application_buildid: 20161222153425 17:21:01 INFO - mozversion application_changeset: 698b5719c9ee8cefe0a894c37c1ef865e34d0769 17:21:01 INFO - mozversion application_display_name: Nightly 17:21:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:21:01 INFO - mozversion application_name: Firefox 17:21:01 INFO - mozversion application_remotingname: firefox 17:21:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:21:01 INFO - mozversion application_vendor: Mozilla 17:21:01 INFO - mozversion application_version: 53.0a1 17:21:01 INFO - mozversion platform_buildid: 20161222153425 17:21:01 INFO - mozversion platform_changeset: 698b5719c9ee8cefe0a894c37c1ef865e34d0769 17:21:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:21:01 INFO - mozversion platform_version: 53.0a1 17:21:01 INFO - using testdate: 1482456061 17:21:01 INFO - actual date: 1482456061 17:21:01 INFO - starting webserver on 'localhost:54763' 17:21:01 INFO - SUITE-START | Running 2 tests 17:21:01 INFO - TEST-START | dromaeo_css 17:21:01 INFO - operating with platform_type : linux_ 17:21:01 INFO - Initialising browser for dromaeo_css test... 17:21:01 INFO - TEST-INFO | started process 11254 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZcJiS5/profile http://localhost:54763/getInfo.html) 17:21:01 INFO - PROCESS | 11254 | 1482456061879 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 17:21:04 INFO - PROCESS | 11254 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 17:21:04 INFO - PROCESS | 11254 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 17:21:04 INFO - PROCESS | 11254 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 17:21:04 INFO - PROCESS | 11254 | __metrics Screen width/height:1600/1200 17:21:04 INFO - PROCESS | 11254 | colorDepth:24 17:21:04 INFO - PROCESS | 11254 | Browser inner width/height: 1024/697 17:21:04 INFO - PROCESS | 11254 | __metrics 17:21:05 INFO - PROCESS | 11254 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:21:05 INFO - PROCESS | 11254 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:21:06 INFO - PROCESS | 11254 | console.error: PushService: 17:21:06 INFO - PROCESS | 11254 | stateChangeProcessEnqueue: Error transitioning state 17:21:06 INFO - PROCESS | 11254 | UnknownError 17:21:06 INFO - TEST-INFO | 11254: exit 0 17:21:06 INFO - Browser initialized. 17:21:06 INFO - Running cycle 1/1 for dromaeo_css test... 17:21:06 INFO - TEST-INFO | started process 11337 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZcJiS5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:18 INFO - PROCESS | 11337 | RSS: Main: 147447808 17:21:18 INFO - PROCESS | 11337 | 17:22:09 INFO - PROCESS | 11337 | 1482456129900 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] 17:24:19 INFO - PROCESS | 11337 | Cycle 1(1): loaded http://localhost:54763/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54763/tests/dromaeo/cssquery-ext.html) 17:24:21 INFO - PROCESS | 11337 | RSS: Main: 244613120 17:24:21 INFO - PROCESS | 11337 | 17:26:07 INFO - PROCESS | 11337 | ************************* 17:26:07 INFO - PROCESS | 11337 | A coding exception was thrown and uncaught in a Task. 17:26:07 INFO - PROCESS | 11337 | 17:26:07 INFO - PROCESS | 11337 | Full message: ReferenceError: fetch is not defined 17:26:07 INFO - PROCESS | 11337 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 17:26:07 INFO - PROCESS | 11337 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 17:26:07 INFO - PROCESS | 11337 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 17:26:07 INFO - PROCESS | 11337 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:26:07 INFO - PROCESS | 11337 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:26:07 INFO - PROCESS | 11337 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:26:07 INFO - PROCESS | 11337 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 17:26:07 INFO - PROCESS | 11337 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:26:07 INFO - PROCESS | 11337 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:26:07 INFO - PROCESS | 11337 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:26:07 INFO - PROCESS | 11337 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 17:26:07 INFO - PROCESS | 11337 | 17:26:07 INFO - PROCESS | 11337 | ************************* 17:27:23 INFO - PROCESS | 11337 | Cycle 1(1): loaded http://localhost:54763/tests/dromaeo/cssquery-ext.html (next: http://localhost:54763/tests/dromaeo/cssquery-jquery.html) 17:27:24 INFO - PROCESS | 11337 | RSS: Main: 240689152 17:27:24 INFO - PROCESS | 11337 | 17:30:26 INFO - PROCESS | 11337 | Cycle 1(1): loaded http://localhost:54763/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54763/tests/dromaeo/cssquery-mootools.html) 17:30:26 INFO - PROCESS | 11337 | RSS: Main: 228773888 17:30:26 INFO - PROCESS | 11337 | 17:33:28 INFO - PROCESS | 11337 | Cycle 1(1): loaded http://localhost:54763/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54763/tests/dromaeo/cssquery-prototype.html) 17:33:29 INFO - PROCESS | 11337 | RSS: Main: 230563840 17:33:29 INFO - PROCESS | 11337 | 17:36:32 INFO - PROCESS | 11337 | Cycle 1(1): loaded http://localhost:54763/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54763/tests/dromaeo/cssquery-yui.html) 17:36:32 INFO - PROCESS | 11337 | RSS: Main: 187580416 17:36:32 INFO - PROCESS | 11337 | 17:39:35 INFO - PROCESS | 11337 | Cycle 1(1): loaded http://localhost:54763/tests/dromaeo/cssquery-yui.html (next: http://localhost:54763/tests/dromaeo/cssquery-dojo.html) 17:39:35 INFO - PROCESS | 11337 | RSS: Main: 272465920 17:39:35 INFO - PROCESS | 11337 | 17:39:35 INFO - PROCESS | 11337 | __start_tp_report 17:39:35 INFO - PROCESS | 11337 | _x_x_mozilla_page_load 17:39:35 INFO - PROCESS | 11337 | _x_x_mozilla_page_load_details 17:39:35 INFO - PROCESS | 11337 | |i|pagename|runs| 17:39:35 INFO - PROCESS | 11337 | |0;dojo.html;3111.1288839125045;3171.682831716827;3174.5555622212887;3206.0381885434394;3233.256351039261;5308.6814791112465;5335.439778823221;5383.596230282735;5384.084705600052;5430.619856610039;5331.333583302077;5357.08929481988;5363.275957745703;5381.381141168767;5455.890882182364;4867.2942423348595;4896.510348965111;4913.582345500637;4925.556699897009;4952.430470495885;4977.377827771528;5004.12427825131;5010.574101201402;5012.273220383042;5022.748862556876;4675.602573781232;4718.749531374188;4808.110499557593;4937.938343256181;4990.65065445419;4860.7326597037145;4873.390826146731;4874.65877388583;4953.975230123826;4961.776720047606;4819.975900120512;4850.393700787402;4865.318855360252;4881;4904.60763138948;5254.369475662907;5300.284461597674;5420.783168673248;5421.38640714743;5433.402325744165;5694.886102277973;5733.88532229353;5752.913706294409;5761.625494342854;5820.738066787005;5782.3639399666;5813.622114562582;5813.854653633651;5816.651000939957;5819.272590926134;5259.815906443256;5389.380221274564;5392.946070539245;5395.62230643859;5404.91892621611;5839.9124013139835;5846.912296315473;5889.764409423661;5903.498202652822;5924.666949936316;5621.353544342372;5631.63394379364;5637.126245431965;5642.887142257132;5655.717214139277;5620.269364982526;5631.324241091095;5764.250647415893;5805.7387417566315;5810.1865738796605;5643.181738647955;5681.119426488901;5686.800961966312;5727.856803579861;5784.421557844266;5741.45456181662;5755.539556835443;5757.827265182052;5759.65442073477;5766.394528574523;4299.892502687395;4302.311630139163;4307.310830267143;4308.138372325548;4342.1532801103485;3807.162424266657;3837.808109594509;3852.614738526125;3891.591382904811;3897.2595206910596;5494.945050549523;5503.807366742145;5504.9449505504435;5540.307461567304;5560.221568980346;5735.598508104476;5811.622244554091;5814.738336774855;5824.621399609012;5834.978878696211;5344.358676958712;5407.351117865881;5408.37803652585;5452.100403433468;5509.586780991362;4483.349914262414;4750.928736068892;4777.378940737682;4821.686590371614;4832.864276894927;5991.670458124758;5998.730057147439;6033.607815491979;6038.788642397583;6049.30433000193;5800.1589769484035;5802.448767367094;5803.506701930383;5810.302763668386;5817.127430885401;5778.306603207474;5794.82615521535;6025;6075.878482430415;6088.056351265561;5759.942400576108;5762.1644861494015;5772.797952071825;5777.94222057774;5781.508571771446;5873.588848780544;5875.3243377012195;5875.853103672443;5876.3536011037895;5878.3239927408895;5521.254630624814;5599.440055994368;5607.971960140173;5630.4369563045;5731.054376027909;5626.85932851682;5658.7736490541565;5661.688607126647;5694.686792226467;5710.2576665033275;5513.531349835309;5514.228008078952;5648.915266271091;5665.433456654301;5671.461211184931;5642.294713160855;5673.602847800615;5700.543956483554;5719.970405327081;5817.127430885401;5569.164625306236;5587.77648894056;5597.076482380525;5598.10430331145;5600.467955544217 17:39:35 INFO - PROCESS | 11337 | |1;ext.html;571.9285089363829;579.226732312363;582.6154737873003;596.862721574038;598.536134495766;33117.33765324695;41615.37576936345;43378.99999999996;43380.132397352034;45929.77035114828;45096.206239783256;45430.772846135726;45488;45513.544864551426;45536.77231613846;8773.341999350057;8976.820463590724;8982.685606003792;8991.280697544213;8993.561030235163;8129.065157506903;8359.164083591653;10245.180385569136;10457.581696732159;10484.790304193912;10821.567137314498;10905.34567925923;10905.636795400575;10909.23635345526;10910.727231819188;10650.307729997525;10725.946370268137;10730.30253033558;10780.353178809297;10795.5681772729;10265.332753371007;10288.485575721184;10314.896851031468;10316.58733650653;10321.483925803679;41031.79484102561;41413.58586414128;41437;41508.1698366031;41588.37617435711;2889.6676882158613;3011.744001759853;3018.8792448302042;3221.532877732739;3631.7094632429344;31397.707956352588;32247.35505289881;32322.191945201324;32555.51166732501;32754.508682369782;3965.266425711253;4204.516480604739;4248.256555102876;4254.276772948606;4278.058827058074;366075;368537.09017827513;368586;369253;370472.0334788269;52328.73835630798;53101;53206.46793532015;53262.86948522093;53376.199357009675;54560.72719636376;54760.452395475535;54796.45203547914;54878;54917.389456844896;13570.59288221355;13870.306484675928;13896.235707036009;13907.65230869216;13910.51313204033;10267.435291058913;10598.9930956559;11488;11505.194636375541;11511.597094101666;16367.181640917906;16421.096839673693;16522.50432487027;17153.571160720832;17193;6590.967045164839;6653.168353955755;6726.831829204211;6783.253842077367;6819.78357033956;902.7156445719577;913.4062859141478;941.275218082073;958.2669258017622;1015.9796804063877;28528.429431411256;30600.38799224003;30924;31255.84372078125;31574.526382104286;1017.2777328096986;1044.9529771160164;1045.9738506537396;1057.3708643357345;1061.5806756331206;16000.519984400487;16514;16687.582810429838;16735.581610459834;16838.915805420893;501051;516978;517844;542881;715473;52777;53576.19635705467;53633;53772.19341709877;53803.19295210575;1343.1605246720799;1352.9932350338186;1354.850966393696;1376.126159888478;1395.5743498232932;27012.72987270102;27592.586111208748;28595.857020714764;28894.277643059093;29388;45119.32321015253;45465.54534454613;45636;45699.31451028304;45781.31328030016;465043;466884;467200;470267;472693;23787.0047290071;24417.51164976726;24587;25792.09727659523;26124.085657002674;41296.587034129276;41905.37141942934;42019;42029.36955945602;42449.36325955051;33448.498272525445;34650.65349346474;35400.64599354076;35917.640823592475;36034.63965360313;33401.66598334083;35257;35713;35728.64271357253;36237.45643815292;30579.694203057683;31222.06333809989;31308.530372044894;34645.307093857475;34783.652163479055;26113.869430652725;29094.709052910046;29263.122106336843;30966.380672385974;31100.66744994089;25109.87445062763;25111.49777004486;25370.619440708775;25640.871795640902;25815.225543233733 17:39:35 INFO - PROCESS | 11337 | |2;jquery.html;399.22550252510126;399.6682753314749;404.1674151248428;404.3913909566106;424.2300225091459;13536.864631353683;17578.296868125275;18295.451136465927;19115.71326430103;19204.23183072679;18735.3442629508;19086.904565477154;19147;19417.126229319678;19571.119299631515;5737.19679244905;5755.712214389285;5925.970370148143;5949.643021418707;5957.165996760457;5803.941960580382;5916.083007133901;5923.881522369572;5924.37794031627;5926.348101708809;5693.459121383462;5792.9283082629845;5886.676232807194;5901.409859014086;5915.112733090049;5568.415316391761;5842.88314233715;5849.415058494159;5871.031279838843;5873.588848780587;5636.633618814805;5636.971815140939;5638.718064096779;5669.376368599451;5671.489565939085;12041;12150;21340.893295533424;22165.00257488401;22667.77332226673;2237.79859812616;2396.6405039244078;2456.2262887190477;2621.5805471124527;2857.1428571428487;8752.912470875273;8919.375643704943;9516.809663806755;9703.17523010545;13267.402966866513;1876.7654043244595;1889.725989731495;1899.9525011874675;1940.4178746376172;1961.548843765942;77170;77525.61237193835;77853.61073194598;78445.60777196134;78700;10119.59521619127;10161.745956351002;10369.066783989478;10694.946525267324;10998.395088270221;13594.660133496742;14554.27228638585;14654.120752754869;14709.852901470847;14726.11643301384;7404.666789994463;7561.168271490132;7834.725784597512;7940.801479963047;7991;7478.5138965967035;7502.324790768748;7686.961565192139;7890.8816367754525;7939.166387529342;4337.609615134653;4521.59305662492;4707.7175369477945;4882;4909.800266707643;1955.8044195580612;1978;2040.8367330613514;2057.084597354163;2059.4748339173416;843.4053991935671;911.8677791720163;937.7889974755625;955.1642312976146;995.9651412200683;16443.013419194886;17634.382796602058;18392.908035459735;18643.813561864208;18669.698353754207;896.2964073202505;917.8302014127407;932;941.1529623338913;956.1155930764015;9867.210623150275;10461;10463.68608941733;10491.370517768959;10560.52477638508;412737;415162;421289;423392;424410;82337.58831205805;84324;85334;86105.56947215224;86118;946.6024269806697;951.2627713522076;957.042957042957;959.8704174936433;962.4081190068058;15562.844371556139;15771.605709857346;16064.518064457618;16321.836781632506;16499.58751031234;38277;38699.6130038696;38813.611863881;39221.01947451336;40008.99977500586;53329.733351334544;54190;54495.45504544904;55021;55069.173962389796;8224.917750822655;8299.502029878227;8493.32053435736;8523.275521580736;8757.211850933192;11751.353675547927;12433.626991190278;12448.875511244772;12540;12931.612051638465;11100.501432306535;11116.888831111586;11294.378809165575;11427.542898283975;11463.828042579202;10995.34027958325;11052.447377631248;11121.332720036824;11121.88878111241;11293.887061129284;11162.609308674157;11279.09767218637;11301.37842418642;11353.432328383713;11565.884341156481;8226.588670566329;8471.49171049739;8548.91451085506;8610.095939926217;8678.696245631625;8471.79733269342;10125.898741012496;11199.776004479701;11204.43977801123;11770.352630605057 17:39:35 INFO - PROCESS | 11337 | |3;mootools.html;1388.9930550347246;1407.9859201407985;1509.8188217413913;1712.1353716373246;1754.175537497376;7677.232276772327;8159.959200203991;8169.305609023225;8176.754697359074;8177.509349439038;7158.427325813936;7390.297921697444;7638.54168749874;7847.960760196205;7872.134065252818;2176.4341271269504;2209.160519002785;2213.214308920334;2214.77852214778;2218.0240694094546;2186.529896072344;2192.3642143778284;2210.4694873230387;2228.2869481765842;2257.9887100564474;2196.494806194576;2199.7910198531113;2217.0466699319286;2223.8109760670363;2231.364061242546;2127.904244309002;2152.2251989283845;2155.5257843274453;2160.092761040359;2164.5346250556127;2204.7684993075654;2218.2901471529117;2221.766714494987;2222.055626358791;2224.021430570544;8068.556229407381;8079.070906845671;8143.42995990275;8144.877826832602;8182.427230093896;5083.415407228142;5223.973880130575;5224.294720212799;5228.163493841005;5233.738313084368;3187.3107253155863;3279.360524697685;3366.848491817874;3383.5263063171174;3383.678550537695;1082.3019152646577;1091.3015669971226;1093.6828319787332;1132.4847194526553;1177.3406892140429;10919;10971.945140274327;11001.944990275077;11010;11022.228444008922;4468.687191896536;4588.541145885385;4665.790039448233;4678.789454474557;4681.765911704401;9237.168654820964;9247.861282080774;9252.7686807829;9303.441793492411;9305.441673499612;5326.973365133227;5367.221752845816;5371.597130215249;5376.623636345496;5377.112776391929;4046.2109888571445;4676.462206846256;4871.415430148405;4944.159492886218;4950.653454258239;3768.8680896169053;3827.3876179811095;3857.498525191707;3893.785841778673;3916.060145565101;1347.595721283611;1350.8784209421199;1356.8236129303127;1360.0411709744606;1371.1978492581752;724.9782506524813;793.4168386236112;884.9469031858109;915.8031023329883;920.6869664314165;5410.702411367334;5422.972885135628;5553.250311208016;5565.026120428909;5568.9443105569235;804.4690504267272;813.8616435206029;818.300353198019;831.1646794971136;863.576847344809;3728.105254738897;3760.4735337052834;3791.5071040765074;3808.562015368268;3888.6305800948867;9908.108270255567;10269.435181064964;10292.948535257425;10560.366378017343;10561.735956600993;10392.168626510012;10415.583376665;10418.426986515662;10441.32131411471;10537.9473102634;7586.696532138652;7740.148583655906;7746.49647772893;7750.341220996278;7763.92236077632;5491.341039075336;5527.309086364205;5588.6646801192055;5700.771969121354;5753.2520772301;9031.232345250464;9328.39365441244;9383.202427793714;9429.57566909477;9443.858342124737;9344.392614480308;9443.291753118407;9479.952600237231;9619.855702164614;9628.9037109628;6814.4548436124;6904.102466679299;7010.754623588354;7067.434605231672;7069.787906362817;7573.848523029398;7578.962105189439;7612.5051871630385;7671.232876712284;7672.578008209602;6854.314568543105;6873.553219040747;6951.548149370275;6975.511714179958;7148.964255178691;6858.2455929848675;6919.416180482381;6992.510524263253;7041.049458323266;7041.436685065285;6086.178365920544;6117.0824376342125;6162.845928851814;6163.322034576283;6165.32181460048;4581.702189357681;4672.415948006499;4709.976450117864;4769.070031343855;4790.832320868893;4646.907061858811;4756.048790242035;4816.145783873164;4818.349522814376;4882.853514394574 17:39:35 INFO - PROCESS | 11337 | |4;prototype.html;378.56276001218595;383.35022137476955;383.8176865988656;384.11461581055676;386.19093000164656;5139.922901156477;5191.948080519193;5198.350206224222;5201.401838788541;5215.157739032227;4884.413870335556;4971.651984361087;4988.301637770715;5062.341895553573;5129.615278854091;4100.605794030029;4104.753714777108;4111.876643700693;4124.83500659974;4126.814293356806;4101.610347017044;4115.135821477494;4133.5659755725665;4138.461999940018;4154.480689913761;3944.152007318426;3955.6044395560502;4040.313146765057;4047.433359329696;4103.405006274089;4046.2196633481467;4047;4058.127502586958;4058.1883623275166;4070.715049946505;3657.9817100914593;3676.3198808220563;3684.1342284563107;3690.4833323334756;3694.3350196964534;4710.845842768544;4914.36113305268;4939.382577177853;5008.670967999861;5021.372328458942;4599.609033232179;4633.513481084505;4693.319468677057;4696.436427628672;4711;5278.340207474066;5320.068987927098;5323.281357016792;5328.387235467893;5356.276902618136;3963.3856752203455;3964.6630036446936;4026.617471340218;4036.979815100906;4048.271311163989;5456.236126942231;5468.781248750045;5491.972540137313;5518.172274158908;5518.641288316287;4103.671706263492;4106.603754723408;4113.362428823537;4117.135401565645;4139.9793001035405;4152.314868046798;4152.335626299778;4183.45615070045;4206.579342065769;4214.957850421518;4343.978280108579;4562.110388474216;4573.702709323949;4584.15193189261;4601.953980460153;5383.946160538345;5408.3509978802795;5410.269613602113;5411.2965314508865;5441.211024401518;1371.5748118083427;1374.9656258593616;1380.7445622559858;1395.2117053864533;1396.211140705498;870.4820631724114;887.2946007923716;908.5548081440047;928.5078908178676;938.6808485115093;5049.394072711299;5087;5092.516210959953;5110;5135.281060651511;5354.571634269249;5359.330083739533;5363.758630861621;5368.302120724359;5390.649607775482;4823.879403014953;4952.5542701157065;5007.47421520735;5095.490450954949;5101.923471147936;2822.788290878192;2831.830090194595;2836.3334616365137;2839.531477306263;2842.4030953499996;5425.159100339453;5531.700050488132;5604.075327570987;5634.52106570946;5645.995671536545;5351.210666453502;5420.945790542123;5421.295231619864;5425.294711687534;5448.264484294648;5530.972345138249;5546.833594992076;5561.416051314553;5567.944320556823;5606.691631960118;5065.746712664279;5284.339457567804;5333.546648534918;5337.199420086863;5398.784048638167;5283.973580132075;5290.867728306823;5300.2049692546425;5315.202719592092;5319.654222475527;5534.225208470737;5620.522255608319;5656.915146272891;5671.092625179951;5689.317281926195;3793.7534060285993;3889.455476233273;3907.941380879346;4010.438538604539;4011;5518.406349015956;5615.073512870331;5627.437256274504;5628.802991895264;5636.971815140898;5525.198846167364;5543.611947163821;5544.390117087198;5546.611737178358;5554.805581804618;5306.734663266744;5489.423610520837;5524.558035357081;5525.696086715269;5550.639208451437;5294.97352513235;5506.201600767947;5508;5510.972445137749;5513.393526711979;5367.382750983531;5465.524308436779;5478.780848766164;5506.0089183947275;5510.752016159202;5352.464753524617;5356.839294821161;5357.249985002025;5366.946330536801;5385.2191432241325 17:39:35 INFO - PROCESS | 11337 | |5;yui.html;638.5274896576535;642.0978525172135;654.7086546486813;670.9563878347907;744.6500144931882;12941.676458088541;14233;14300.785488217673;14942.477013304537;15003.399864005427;15663.843361566382;15957.84042159578;16057.437989670363;16193.433229836957;16211.351545938149;805.1948051948052;822.9465084769471;824.884516167737;824.905135909369;825.3397282174267;553.0653196098581;571.5627544928134;698.811320943345;716.8172116110385;809.3565615335792;828.3125006244802;848.3298194426395;855.1832999485507;856.5289090999956;856.9914300856974;855.6235256487188;855.7774978505571;856.2465030772943;856.297835774665;856.4304737349655;500.9323741294915;523.8664140644149;530.1835173832294;540.956723462126;629.7355110853453;11772.2936623802;11963.1625786195;12098.697532561668;12309;12329.568465103679;12451;12789.872101278961;12861.485540578366;12895.16181448203;12945.87054129456;12230.571929982409;12357.546328411385;12402.81395779064;12697.873021269854;12857.292848893307;4154.729942553724;4212.578742125794;4348.652107831397;4361.629261512775;4394;79025.60487197584;79209;79311.60344198241;80939.59530202311;81165;10739.946300268604;10856.240063195659;10861.945690271497;10916.235863489479;11021.228514003942;17837.197326120357;17952.461426157235;17996.460106196573;18029.27882884696;18053.187606557738;7306.890396644055;7331.303526165006;7431.925680743231;7483.363914067365;7514.135874374408;4149.792510374469;4176.582341765799;4229.894252643709;4341.934870976938;4375.190589740908;3359.328134373135;3460.8788692395647;3463.1861512544533;3464.6535346465657;3481.721462283011;569.9202111704365;577.7400169923551;583.5098517245449;597.1997523318687;599.2688919518181;528.6854321678595;553.4520824383831;588.7586089703201;643.1863692429081;660.4716227018366;8899.866502002475;8924.241439477717;9280.953595232115;9319.767005824908;9523.714288571355;511.90529751996;572.6478215897193;580.7270582826065;587.7913340764053;607.1075518987082;4432.047109871393;4482.986406011785;4641.396618439581;4662;4715.316279139574;85056.57471712725;86879.56560217285;87652.5617371909;89402.55298723595;90806.54596727106;73504;75864;76330.61834690902;78628;79380;69742;70139.64930175418;70671;70752;72381.2761872385;8712.390132690904;8841;8943.686970955985;8944.910550894409;8990.550472476481;46478.30282545832;47399;47563.04873902572;48616.270755937985;49299.26051109308;63464.68267658632;63973.36026639674;64416;66766.65566787747;66853.66573167103;11491.942540287246;11529.1929564933;11604.535818567163;12220.55888223553;12602.799411796612;12202.572909948109;12977.156484828456;13602.38789254466;14182.43270269219;14368;11516.251443656136;11935.164538482222;12791.87208127944;13264.469421223042;13265.801012985006;10135.391876487434;11684.591039313924;11786.233894796811;11941.104417168573;12252.326122063016;11689.590864319707;12097.2136811107;12152.149349545447;12313.384330783247;12601.936990314989;10125;10498.895011050097;10517.526711297856;10622.787544248917;11323.660290191307;10371.37793575219;11092.655951623145;11212.719682008015;11318.773624527628;11353.772924541297 17:39:35 INFO - PROCESS | 11337 | __end_tp_report 17:39:35 INFO - PROCESS | 11337 | __start_cc_report 17:39:35 INFO - PROCESS | 11337 | _x_x_mozilla_cycle_collect,1211 17:39:35 INFO - PROCESS | 11337 | __end_cc_report 17:39:35 INFO - PROCESS | 11337 | __startTimestamp1482457175545__endTimestamp 17:39:35 INFO - PROCESS | 11337 | 17:39:35 INFO - PROCESS | 11337 | ------- Summary: start ------- 17:39:35 INFO - PROCESS | 11337 | Number of tests: 6 17:39:35 INFO - PROCESS | 11337 | 17:39:35 INFO - PROCESS | 11337 | [#0] dojo.html Cycles:165 Average:5354.93 Median:5599.44 stddev:625.55 (11.2%) stddev-sans-first:602.19 17:39:35 INFO - PROCESS | 11337 | Values: 3111.1 3171.7 3174.6 3206.0 3233.3 5308.7 5335.4 5383.6 5384.1 5430.6 5331.3 5357.1 5363.3 5381.4 5455.9 4867.3 4896.5 4913.6 4925.6 4952.4 4977.4 5004.1 5010.6 5012.3 5022.7 4675.6 4718.7 4808.1 4937.9 4990.7 4860.7 4873.4 4874.7 4954.0 4961.8 4820.0 4850.4 4865.3 4881.0 4904.6 5254.4 5300.3 5420.8 5421.4 5433.4 5694.9 5733.9 5752.9 5761.6 5820.7 5782.4 5813.6 5813.9 5816.7 5819.3 5259.8 5389.4 5392.9 5395.6 5404.9 5839.9 5846.9 5889.8 5903.5 5924.7 5621.4 5631.6 5637.1 5642.9 5655.7 5620.3 5631.3 5764.3 5805.7 5810.2 5643.2 5681.1 5686.8 5727.9 5784.4 5741.5 5755.5 5757.8 5759.7 5766.4 4299.9 4302.3 4307.3 4308.1 4342.2 3807.2 3837.8 3852.6 3891.6 3897.3 5494.9 5503.8 5504.9 5540.3 5560.2 5735.6 5811.6 5814.7 5824.6 5835.0 5344.4 5407.4 5408.4 5452.1 5509.6 4483.3 4750.9 4777.4 4821.7 4832.9 5991.7 5998.7 6033.6 6038.8 6049.3 5800.2 5802.4 5803.5 5810.3 5817.1 5778.3 5794.8 6025.0 6075.9 6088.1 5759.9 5762.2 5772.8 5777.9 5781.5 5873.6 5875.3 5875.9 5876.4 5878.3 5521.3 5599.4 5608.0 5630.4 5731.1 5626.9 5658.8 5661.7 5694.7 5710.3 5513.5 5514.2 5648.9 5665.4 5671.5 5642.3 5673.6 5700.5 5720.0 5817.1 5569.2 5587.8 5597.1 5598.1 5600.5 17:39:35 INFO - PROCESS | 11337 | 17:39:35 INFO - PROCESS | 11337 | [#1] ext.html Cycles:180 Average:60367.37 Median:34717.15 stddev:126252.41 (363.7%) stddev-sans-first:126526.75 17:39:35 INFO - PROCESS | 11337 | Values: 571.9 579.2 582.6 596.9 598.5 33117.3 41615.4 43379.0 43380.1 45929.8 45096.2 45430.8 45488.0 45513.5 45536.8 8773.3 8976.8 8982.7 8991.3 8993.6 8129.1 8359.2 10245.2 10457.6 10484.8 10821.6 10905.3 10905.6 10909.2 10910.7 10650.3 10725.9 10730.3 10780.4 10795.6 10265.3 10288.5 10314.9 10316.6 10321.5 41031.8 41413.6 41437.0 41508.2 41588.4 2889.7 3011.7 3018.9 3221.5 3631.7 31397.7 32247.4 32322.2 32555.5 32754.5 3965.3 4204.5 4248.3 4254.3 4278.1 366075.0 368537.1 368586.0 369253.0 370472.0 52328.7 53101.0 53206.5 53262.9 53376.2 54560.7 54760.5 54796.5 54878.0 54917.4 13570.6 13870.3 13896.2 13907.7 13910.5 10267.4 10599.0 11488.0 11505.2 11511.6 16367.2 16421.1 16522.5 17153.6 17193.0 6591.0 6653.2 6726.8 6783.3 6819.8 902.7 913.4 941.3 958.3 1016.0 28528.4 30600.4 30924.0 31255.8 31574.5 1017.3 1045.0 1046.0 1057.4 1061.6 16000.5 16514.0 16687.6 16735.6 16838.9 501051.0 516978.0 517844.0 542881.0 715473.0 52777.0 53576.2 53633.0 53772.2 53803.2 1343.2 1353.0 1354.9 1376.1 1395.6 27012.7 27592.6 28595.9 28894.3 29388.0 45119.3 45465.5 45636.0 45699.3 45781.3 465043.0 466884.0 467200.0 470267.0 472693.0 23787.0 24417.5 24587.0 25792.1 26124.1 41296.6 41905.4 42019.0 42029.4 42449.4 33448.5 34650.7 35400.6 35917.6 36034.6 33401.7 35257.0 35713.0 35728.6 36237.5 30579.7 31222.1 31308.5 34645.3 34783.7 26113.9 29094.7 29263.1 30966.4 31100.7 25109.9 25111.5 25370.6 25640.9 25815.2 17:39:35 INFO - PROCESS | 11337 | 17:39:35 INFO - PROCESS | 11337 | [#2] jquery.html Cycles:180 Average:26173.43 Median:422340.50 stddev:69369.15 (16.4%) stddev-sans-first:69536.75 17:39:35 INFO - PROCESS | 11337 | Values: 399.2 399.7 404.2 404.4 424.2 13536.9 17578.3 18295.5 19115.7 19204.2 18735.3 19086.9 19147.0 19417.1 19571.1 5737.2 5755.7 5926.0 5949.6 5957.2 5803.9 5916.1 5923.9 5924.4 5926.3 5693.5 5792.9 5886.7 5901.4 5915.1 5568.4 5842.9 5849.4 5871.0 5873.6 5636.6 5637.0 5638.7 5669.4 5671.5 12041.0 12150.0 21340.9 22165.0 22667.8 2237.8 2396.6 2456.2 2621.6 2857.1 8752.9 8919.4 9516.8 9703.2 13267.4 1876.8 1889.7 1900.0 1940.4 1961.5 77170.0 77525.6 77853.6 78445.6 78700.0 10119.6 10161.7 10369.1 10694.9 10998.4 13594.7 14554.3 14654.1 14709.9 14726.1 7404.7 7561.2 7834.7 7940.8 7991.0 7478.5 7502.3 7687.0 7890.9 7939.2 4337.6 4521.6 4707.7 4882.0 4909.8 1955.8 1978.0 2040.8 2057.1 2059.5 843.4 911.9 937.8 955.2 996.0 16443.0 17634.4 18392.9 18643.8 18669.7 896.3 917.8 932.0 941.2 956.1 9867.2 10461.0 10463.7 10491.4 10560.5 412737.0 415162.0 421289.0 423392.0 424410.0 82337.6 84324.0 85334.0 86105.6 86118.0 946.6 951.3 957.0 959.9 962.4 15562.8 15771.6 16064.5 16321.8 16499.6 38277.0 38699.6 38813.6 39221.0 40009.0 53329.7 54190.0 54495.5 55021.0 55069.2 8224.9 8299.5 8493.3 8523.3 8757.2 11751.4 12433.6 12448.9 12540.0 12931.6 11100.5 11116.9 11294.4 11427.5 11463.8 10995.3 11052.4 11121.3 11121.9 11293.9 11162.6 11279.1 11301.4 11353.4 11565.9 8226.6 8471.5 8548.9 8610.1 8678.7 8471.8 10125.9 11199.8 11204.4 11770.4 17:39:35 INFO - PROCESS | 11337 | 17:39:35 INFO - PROCESS | 11337 | [#3] mootools.html Cycles:180 Average:5393.29 Median:5224.13 stddev:2988.09 (57.2%) stddev-sans-first:2981.31 17:39:35 INFO - PROCESS | 11337 | Values: 1389.0 1408.0 1509.8 1712.1 1754.2 7677.2 8160.0 8169.3 8176.8 8177.5 7158.4 7390.3 7638.5 7848.0 7872.1 2176.4 2209.2 2213.2 2214.8 2218.0 2186.5 2192.4 2210.5 2228.3 2258.0 2196.5 2199.8 2217.0 2223.8 2231.4 2127.9 2152.2 2155.5 2160.1 2164.5 2204.8 2218.3 2221.8 2222.1 2224.0 8068.6 8079.1 8143.4 8144.9 8182.4 5083.4 5224.0 5224.3 5228.2 5233.7 3187.3 3279.4 3366.8 3383.5 3383.7 1082.3 1091.3 1093.7 1132.5 1177.3 10919.0 10971.9 11001.9 11010.0 11022.2 4468.7 4588.5 4665.8 4678.8 4681.8 9237.2 9247.9 9252.8 9303.4 9305.4 5327.0 5367.2 5371.6 5376.6 5377.1 4046.2 4676.5 4871.4 4944.2 4950.7 3768.9 3827.4 3857.5 3893.8 3916.1 1347.6 1350.9 1356.8 1360.0 1371.2 725.0 793.4 884.9 915.8 920.7 5410.7 5423.0 5553.3 5565.0 5568.9 804.5 813.9 818.3 831.2 863.6 3728.1 3760.5 3791.5 3808.6 3888.6 9908.1 10269.4 10292.9 10560.4 10561.7 10392.2 10415.6 10418.4 10441.3 10537.9 7586.7 7740.1 7746.5 7750.3 7763.9 5491.3 5527.3 5588.7 5700.8 5753.3 9031.2 9328.4 9383.2 9429.6 9443.9 9344.4 9443.3 9480.0 9619.9 9628.9 6814.5 6904.1 7010.8 7067.4 7069.8 7573.8 7579.0 7612.5 7671.2 7672.6 6854.3 6873.6 6951.5 6975.5 7149.0 6858.2 6919.4 6992.5 7041.0 7041.4 6086.2 6117.1 6162.8 6163.3 6165.3 4581.7 4672.4 4710.0 4769.1 4790.8 4646.9 4756.0 4816.1 4818.3 4882.9 17:39:35 INFO - PROCESS | 11337 | 17:39:35 INFO - PROCESS | 11337 | [#4] prototype.html Cycles:180 Average:4539.44 Median:5137.60 stddev:1304.38 (25.4%) stddev-sans-first:1270.10 17:39:35 INFO - PROCESS | 11337 | Values: 378.6 383.4 383.8 384.1 386.2 5139.9 5191.9 5198.4 5201.4 5215.2 4884.4 4971.7 4988.3 5062.3 5129.6 4100.6 4104.8 4111.9 4124.8 4126.8 4101.6 4115.1 4133.6 4138.5 4154.5 3944.2 3955.6 4040.3 4047.4 4103.4 4046.2 4047.0 4058.1 4058.2 4070.7 3658.0 3676.3 3684.1 3690.5 3694.3 4710.8 4914.4 4939.4 5008.7 5021.4 4599.6 4633.5 4693.3 4696.4 4711.0 5278.3 5320.1 5323.3 5328.4 5356.3 3963.4 3964.7 4026.6 4037.0 4048.3 5456.2 5468.8 5492.0 5518.2 5518.6 4103.7 4106.6 4113.4 4117.1 4140.0 4152.3 4152.3 4183.5 4206.6 4215.0 4344.0 4562.1 4573.7 4584.2 4602.0 5383.9 5408.4 5410.3 5411.3 5441.2 1371.6 1375.0 1380.7 1395.2 1396.2 870.5 887.3 908.6 928.5 938.7 5049.4 5087.0 5092.5 5110.0 5135.3 5354.6 5359.3 5363.8 5368.3 5390.6 4823.9 4952.6 5007.5 5095.5 5101.9 2822.8 2831.8 2836.3 2839.5 2842.4 5425.2 5531.7 5604.1 5634.5 5646.0 5351.2 5420.9 5421.3 5425.3 5448.3 5531.0 5546.8 5561.4 5567.9 5606.7 5065.7 5284.3 5333.5 5337.2 5398.8 5284.0 5290.9 5300.2 5315.2 5319.7 5534.2 5620.5 5656.9 5671.1 5689.3 3793.8 3889.5 3907.9 4010.4 4011.0 5518.4 5615.1 5627.4 5628.8 5637.0 5525.2 5543.6 5544.4 5546.6 5554.8 5306.7 5489.4 5524.6 5525.7 5550.6 5295.0 5506.2 5508.0 5511.0 5513.4 5367.4 5465.5 5478.8 5506.0 5510.8 5352.5 5356.8 5357.2 5366.9 5385.2 17:39:35 INFO - PROCESS | 11337 | 17:39:35 INFO - PROCESS | 11337 | [#5] yui.html Cycles:180 Average:18234.58 Median:26057.16 stddev:24931.03 (95.7%) stddev-sans-first:24965.95 17:39:35 INFO - PROCESS | 11337 | Values: 638.5 642.1 654.7 671.0 744.7 12941.7 14233.0 14300.8 14942.5 15003.4 15663.8 15957.8 16057.4 16193.4 16211.4 805.2 822.9 824.9 824.9 825.3 553.1 571.6 698.8 716.8 809.4 828.3 848.3 855.2 856.5 857.0 855.6 855.8 856.2 856.3 856.4 500.9 523.9 530.2 541.0 629.7 11772.3 11963.2 12098.7 12309.0 12329.6 12451.0 12789.9 12861.5 12895.2 12945.9 12230.6 12357.5 12402.8 12697.9 12857.3 4154.7 4212.6 4348.7 4361.6 4394.0 79025.6 79209.0 79311.6 80939.6 81165.0 10739.9 10856.2 10861.9 10916.2 11021.2 17837.2 17952.5 17996.5 18029.3 18053.2 7306.9 7331.3 7431.9 7483.4 7514.1 4149.8 4176.6 4229.9 4341.9 4375.2 3359.3 3460.9 3463.2 3464.7 3481.7 569.9 577.7 583.5 597.2 599.3 528.7 553.5 588.8 643.2 660.5 8899.9 8924.2 9281.0 9319.8 9523.7 511.9 572.6 580.7 587.8 607.1 4432.0 4483.0 4641.4 4662.0 4715.3 85056.6 86879.6 87652.6 89402.6 90806.5 73504.0 75864.0 76330.6 78628.0 79380.0 69742.0 70139.6 70671.0 70752.0 72381.3 8712.4 8841.0 8943.7 8944.9 8990.6 46478.3 47399.0 47563.0 48616.3 49299.3 63464.7 63973.4 64416.0 66766.7 66853.7 11491.9 11529.2 11604.5 12220.6 12602.8 12202.6 12977.2 13602.4 14182.4 14368.0 11516.3 11935.2 12791.9 13264.5 13265.8 10135.4 11684.6 11786.2 11941.1 12252.3 11689.6 12097.2 12152.1 12313.4 12601.9 10125.0 10498.9 10517.5 10622.8 11323.7 10371.4 11092.7 11212.7 11318.8 11353.8 17:39:35 INFO - PROCESS | 11337 | -------- Summary: end -------- 17:39:35 INFO - PROCESS | 11337 | 17:39:36 INFO - TEST-INFO | 11337: exit 0 17:39:36 INFO - TEST-OK | dromaeo_css | took 1114899ms 17:39:36 INFO - TEST-START | kraken 17:39:36 INFO - operating with platform_type : linux_ 17:39:36 INFO - Initialising browser for kraken test... 17:39:36 INFO - TEST-INFO | started process 11646 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2_C8Qw/profile http://localhost:54763/getInfo.html) 17:39:36 INFO - PROCESS | 11646 | 1482457176752 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 17:39:38 INFO - PROCESS | 11646 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 17:39:38 INFO - PROCESS | 11646 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 17:39:39 INFO - PROCESS | 11646 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 17:39:39 INFO - PROCESS | 11646 | __metrics Screen width/height:1600/1200 17:39:39 INFO - PROCESS | 11646 | colorDepth:24 17:39:39 INFO - PROCESS | 11646 | Browser inner width/height: 1024/697 17:39:39 INFO - PROCESS | 11646 | __metrics 17:39:40 INFO - PROCESS | 11646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:39:40 INFO - PROCESS | 11646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:39:41 INFO - PROCESS | 11646 | console.error: PushService: 17:39:41 INFO - PROCESS | 11646 | stateChangeProcessEnqueue: Error transitioning state 17:39:41 INFO - PROCESS | 11646 | UnknownError 17:39:41 INFO - TEST-INFO | 11646: exit 0 17:39:41 INFO - Browser initialized. 17:39:41 INFO - Running cycle 1/1 for kraken test... 17:39:41 INFO - TEST-INFO | started process 11728 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2_C8Qw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:39:53 INFO - PROCESS | 11728 | RSS: Main: 146546688 17:39:53 INFO - PROCESS | 11728 | 17:39:56 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54763/tests/kraken/driver.html?testName=audio-beat-detection) 17:39:56 INFO - PROCESS | 11728 | RSS: Main: 201523200 17:39:56 INFO - PROCESS | 11728 | 17:39:59 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54763/tests/kraken/driver.html?testName=audio-dft) 17:40:00 INFO - PROCESS | 11728 | RSS: Main: 180604928 17:40:00 INFO - PROCESS | 11728 | 17:40:03 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54763/tests/kraken/driver.html?testName=audio-fft) 17:40:03 INFO - PROCESS | 11728 | RSS: Main: 262393856 17:40:03 INFO - PROCESS | 11728 | 17:40:05 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54763/tests/kraken/driver.html?testName=audio-oscillator) 17:40:06 INFO - PROCESS | 11728 | RSS: Main: 173731840 17:40:06 INFO - PROCESS | 11728 | 17:40:08 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54763/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:40:09 INFO - PROCESS | 11728 | RSS: Main: 178454528 17:40:09 INFO - PROCESS | 11728 | 17:40:12 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54763/tests/kraken/driver.html?testName=imaging-darkroom) 17:40:12 INFO - PROCESS | 11728 | RSS: Main: 217325568 17:40:12 INFO - PROCESS | 11728 | 17:40:16 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54763/tests/kraken/driver.html?testName=imaging-desaturate) 17:40:16 INFO - PROCESS | 11728 | RSS: Main: 216977408 17:40:16 INFO - PROCESS | 11728 | 17:40:19 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54763/tests/kraken/driver.html?testName=json-parse-financial) 17:40:20 INFO - PROCESS | 11728 | RSS: Main: 215404544 17:40:20 INFO - PROCESS | 11728 | 17:40:21 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54763/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:40:22 INFO - PROCESS | 11728 | RSS: Main: 177721344 17:40:22 INFO - PROCESS | 11728 | 17:40:23 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54763/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:40:24 INFO - PROCESS | 11728 | RSS: Main: 185024512 17:40:24 INFO - PROCESS | 11728 | 17:40:26 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54763/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:40:27 INFO - PROCESS | 11728 | RSS: Main: 194117632 17:40:27 INFO - PROCESS | 11728 | 17:40:29 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54763/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:40:30 INFO - PROCESS | 11728 | RSS: Main: 203177984 17:40:30 INFO - PROCESS | 11728 | 17:40:33 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54763/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:40:33 INFO - PROCESS | 11728 | RSS: Main: 199888896 17:40:33 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | Cycle 1(1): loaded http://localhost:54763/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54763/tests/kraken/driver.html?testName=ai-astar) 17:40:35 INFO - PROCESS | 11728 | RSS: Main: 251334656 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | __start_tp_report 17:40:35 INFO - PROCESS | 11728 | _x_x_mozilla_page_load 17:40:35 INFO - PROCESS | 11728 | _x_x_mozilla_page_load_details 17:40:35 INFO - PROCESS | 11728 | |i|pagename|runs| 17:40:35 INFO - PROCESS | 11728 | |0;ai-astar;110;113;114;113;114;111;110;110;112;111 17:40:35 INFO - PROCESS | 11728 | |1;audio-beat-detection;148;122;123;158;122;125;119;119;151;122 17:40:35 INFO - PROCESS | 11728 | |2;audio-dft;162;161;163;164;161;161;164;160;161;161 17:40:35 INFO - PROCESS | 11728 | |3;audio-fft;76;77;76;105;77;76;77;75;90;77 17:40:35 INFO - PROCESS | 11728 | |4;audio-oscillator;85;84;115;85;85;83;97;87;83;109 17:40:35 INFO - PROCESS | 11728 | |5;imaging-gaussian-blur;118;117;117;117;117;117;116;116;117;117 17:40:35 INFO - PROCESS | 11728 | |6;imaging-darkroom;146;146;147;147;146;147;149;149;148;147 17:40:35 INFO - PROCESS | 11728 | |7;imaging-desaturate;97;96;96;96;95;96;96;95;96;95 17:40:35 INFO - PROCESS | 11728 | |8;json-parse-financial;51;49;50;50;53;53;51;51;51;51 17:40:35 INFO - PROCESS | 11728 | |9;json-stringify-tinderbox;67;67;66;67;67;66;66;66;67;66 17:40:35 INFO - PROCESS | 11728 | |10;stanford-crypto-aes;82;90;81;81;81;83;86;82;85;81 17:40:35 INFO - PROCESS | 11728 | |11;stanford-crypto-ccm;137;128;127;137;123;125;126;135;172;140 17:40:35 INFO - PROCESS | 11728 | |12;stanford-crypto-pbkdf2;167;168;172;165;167;168;177;166;170;164 17:40:35 INFO - PROCESS | 11728 | |13;stanford-crypto-sha256-iterative;64;63;63;62;62;64;65;64;66;68 17:40:35 INFO - PROCESS | 11728 | __end_tp_report 17:40:35 INFO - PROCESS | 11728 | __start_cc_report 17:40:35 INFO - PROCESS | 11728 | _x_x_mozilla_cycle_collect,1568 17:40:35 INFO - PROCESS | 11728 | __end_cc_report 17:40:35 INFO - PROCESS | 11728 | __startTimestamp1482457235841__endTimestamp 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | ------- Summary: start ------- 17:40:35 INFO - PROCESS | 11728 | Number of tests: 14 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.50 stddev:1.62 (1.4%) stddev-sans-first:1.58 17:40:35 INFO - PROCESS | 11728 | Values: 110.0 113.0 114.0 113.0 114.0 111.0 110.0 110.0 112.0 111.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:124.00 stddev:15.09 (12.2%) stddev-sans-first:14.68 17:40:35 INFO - PROCESS | 11728 | Values: 148.0 122.0 123.0 158.0 122.0 125.0 119.0 119.0 151.0 122.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#2] audio-dft Cycles:10 Average:161.80 Median:161.50 stddev:1.40 (0.9%) stddev-sans-first:1.48 17:40:35 INFO - PROCESS | 11728 | Values: 162.0 161.0 163.0 164.0 161.0 161.0 164.0 160.0 161.0 161.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#3] audio-fft Cycles:10 Average:80.60 Median:77.00 stddev:9.61 (12.5%) stddev-sans-first:10.04 17:40:35 INFO - PROCESS | 11728 | Values: 76.0 77.0 76.0 105.0 77.0 76.0 77.0 75.0 90.0 77.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:85.00 stddev:11.72 (13.8%) stddev-sans-first:12.21 17:40:35 INFO - PROCESS | 11728 | Values: 85.0 84.0 115.0 85.0 85.0 83.0 97.0 87.0 83.0 109.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 17:40:35 INFO - PROCESS | 11728 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:1.14 (0.8%) stddev-sans-first:1.12 17:40:35 INFO - PROCESS | 11728 | Values: 146.0 146.0 147.0 147.0 146.0 147.0 149.0 149.0 148.0 147.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 17:40:35 INFO - PROCESS | 11728 | Values: 97.0 96.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 95.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:1.25 (2.4%) stddev-sans-first:1.32 17:40:35 INFO - PROCESS | 11728 | Values: 51.0 49.0 50.0 50.0 53.0 53.0 51.0 51.0 51.0 51.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:67.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 17:40:35 INFO - PROCESS | 11728 | Values: 67.0 67.0 66.0 67.0 67.0 66.0 66.0 66.0 67.0 66.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:82.50 stddev:2.97 (3.6%) stddev-sans-first:3.12 17:40:35 INFO - PROCESS | 11728 | Values: 82.0 90.0 81.0 81.0 81.0 83.0 86.0 82.0 85.0 81.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:136.00 stddev:14.30 (10.5%) stddev-sans-first:15.15 17:40:35 INFO - PROCESS | 11728 | Values: 137.0 128.0 127.0 137.0 123.0 125.0 126.0 135.0 172.0 140.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:168.00 stddev:3.81 (2.3%) stddev-sans-first:4.00 17:40:35 INFO - PROCESS | 11728 | Values: 167.0 168.0 172.0 165.0 167.0 168.0 177.0 166.0 170.0 164.0 17:40:35 INFO - PROCESS | 11728 | 17:40:35 INFO - PROCESS | 11728 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:1.85 (2.9%) stddev-sans-first:1.96 17:40:35 INFO - PROCESS | 11728 | Values: 64.0 63.0 63.0 62.0 62.0 64.0 65.0 64.0 66.0 68.0 17:40:35 INFO - PROCESS | 11728 | -------- Summary: end -------- 17:40:35 INFO - PROCESS | 11728 | 17:40:36 INFO - TEST-INFO | 11728: exit 0 17:40:36 INFO - TEST-OK | kraken | took 60620ms 17:40:36 INFO - SUITE-END | took 1175s 17:40:36 INFO - Completed test suite (00:19:35) 17:40:37 INFO - javascript benchmark 17:40:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3111.1288839125045, 3171.682831716827, 3174.5555622212887, 3206.0381885434394, 3233.256351039261, 5308.6814791112465, 5335.439778823221, 5383.596230282735, 5384.084705600052, 5430.619856610039, 5331.333583302077, 5357.08929481988, 5363.275957745703, 5381.381141168767, 5455.890882182364, 4867.2942423348595, 4896.510348965111, 4913.582345500637, 4925.556699897009, 4952.430470495885, 4977.377827771528, 5004.12427825131, 5010.574101201402, 5012.273220383042, 5022.748862556876, 4675.602573781232, 4718.749531374188, 4808.110499557593, 4937.938343256181, 4990.65065445419, 4860.7326597037145, 4873.390826146731, 4874.65877388583, 4953.975230123826, 4961.776720047606, 4819.975900120512, 4850.393700787402, 4865.318855360252, 4881.0, 4904.60763138948, 5254.369475662907, 5300.284461597674, 5420.783168673248, 5421.38640714743, 5433.402325744165, 5694.886102277973, 5733.88532229353, 5752.913706294409, 5761.625494342854, 5820.738066787005, 5782.3639399666, 5813.622114562582, 5813.854653633651, 5816.651000939957, 5819.272590926134, 5259.815906443256, 5389.380221274564, 5392.946070539245, 5395.62230643859, 5404.91892621611, 5839.9124013139835, 5846.912296315473, 5889.764409423661, 5903.498202652822, 5924.666949936316, 5621.353544342372, 5631.63394379364, 5637.126245431965, 5642.887142257132, 5655.717214139277, 5620.269364982526, 5631.324241091095, 5764.250647415893, 5805.7387417566315, 5810.1865738796605, 5643.181738647955, 5681.119426488901, 5686.800961966312, 5727.856803579861, 5784.421557844266, 5741.45456181662, 5755.539556835443, 5757.827265182052, 5759.65442073477, 5766.394528574523, 4299.892502687395, 4302.311630139163, 4307.310830267143, 4308.138372325548, 4342.1532801103485, 3807.162424266657, 3837.808109594509, 3852.614738526125, 3891.591382904811, 3897.2595206910596, 5494.945050549523, 5503.807366742145, 5504.9449505504435, 5540.307461567304, 5560.221568980346, 5735.598508104476, 5811.622244554091, 5814.738336774855, 5824.621399609012, 5834.978878696211, 5344.358676958712, 5407.351117865881, 5408.37803652585, 5452.100403433468, 5509.586780991362, 4483.349914262414, 4750.928736068892, 4777.378940737682, 4821.686590371614, 4832.864276894927, 5991.670458124758, 5998.730057147439, 6033.607815491979, 6038.788642397583, 6049.30433000193, 5800.1589769484035, 5802.448767367094, 5803.506701930383, 5810.302763668386, 5817.127430885401, 5778.306603207474, 5794.82615521535, 6025.0, 6075.878482430415, 6088.056351265561, 5759.942400576108, 5762.1644861494015, 5772.797952071825, 5777.94222057774, 5781.508571771446, 5873.588848780544, 5875.3243377012195, 5875.853103672443, 5876.3536011037895, 5878.3239927408895, 5521.254630624814, 5599.440055994368, 5607.971960140173, 5630.4369563045, 5731.054376027909, 5626.85932851682, 5658.7736490541565, 5661.688607126647, 5694.686792226467, 5710.2576665033275, 5513.531349835309, 5514.228008078952, 5648.915266271091, 5665.433456654301, 5671.461211184931, 5642.294713160855, 5673.602847800615, 5700.543956483554, 5719.970405327081, 5817.127430885401, 5569.164625306236, 5587.77648894056, 5597.076482380525, 5598.10430331145, 5600.467955544217], "value": 5312.053130241411, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.9285089363829, 579.226732312363, 582.6154737873003, 596.862721574038, 598.536134495766, 33117.33765324695, 41615.37576936345, 43378.99999999996, 43380.132397352034, 45929.77035114828, 45096.206239783256, 45430.772846135726, 45488.0, 45513.544864551426, 45536.77231613846, 8773.341999350057, 8976.820463590724, 8982.685606003792, 8991.280697544213, 8993.561030235163, 8129.065157506903, 8359.164083591653, 10245.180385569136, 10457.581696732159, 10484.790304193912, 10821.567137314498, 10905.34567925923, 10905.636795400575, 10909.23635345526, 10910.727231819188, 10650.307729997525, 10725.946370268137, 10730.30253033558, 10780.353178809297, 10795.5681772729, 10265.332753371007, 10288.485575721184, 10314.896851031468, 10316.58733650653, 10321.483925803679, 41031.79484102561, 41413.58586414128, 41437.0, 41508.1698366031, 41588.37617435711, 2889.6676882158613, 3011.744001759853, 3018.8792448302042, 3221.532877732739, 3631.7094632429344, 31397.707956352588, 32247.35505289881, 32322.191945201324, 32555.51166732501, 32754.508682369782, 3965.266425711253, 4204.516480604739, 4248.256555102876, 4254.276772948606, 4278.058827058074, 366075.0, 368537.09017827513, 368586.0, 369253.0, 370472.0334788269, 52328.73835630798, 53101.0, 53206.46793532015, 53262.86948522093, 53376.199357009675, 54560.72719636376, 54760.452395475535, 54796.45203547914, 54878.0, 54917.389456844896, 13570.59288221355, 13870.306484675928, 13896.235707036009, 13907.65230869216, 13910.51313204033, 10267.435291058913, 10598.9930956559, 11488.0, 11505.194636375541, 11511.597094101666, 16367.181640917906, 16421.096839673693, 16522.50432487027, 17153.571160720832, 17193.0, 6590.967045164839, 6653.168353955755, 6726.831829204211, 6783.253842077367, 6819.78357033956, 902.7156445719577, 913.4062859141478, 941.275218082073, 958.2669258017622, 1015.9796804063877, 28528.429431411256, 30600.38799224003, 30924.0, 31255.84372078125, 31574.526382104286, 1017.2777328096986, 1044.9529771160164, 1045.9738506537396, 1057.3708643357345, 1061.5806756331206, 16000.519984400487, 16514.0, 16687.582810429838, 16735.581610459834, 16838.915805420893, 501051.0, 516978.0, 517844.0, 542881.0, 715473.0, 52777.0, 53576.19635705467, 53633.0, 53772.19341709877, 53803.19295210575, 1343.1605246720799, 1352.9932350338186, 1354.850966393696, 1376.126159888478, 1395.5743498232932, 27012.72987270102, 27592.586111208748, 28595.857020714764, 28894.277643059093, 29388.0, 45119.32321015253, 45465.54534454613, 45636.0, 45699.31451028304, 45781.31328030016, 465043.0, 466884.0, 467200.0, 470267.0, 472693.0, 23787.0047290071, 24417.51164976726, 24587.0, 25792.09727659523, 26124.085657002674, 41296.587034129276, 41905.37141942934, 42019.0, 42029.36955945602, 42449.36325955051, 33448.498272525445, 34650.65349346474, 35400.64599354076, 35917.640823592475, 36034.63965360313, 33401.66598334083, 35257.0, 35713.0, 35728.64271357253, 36237.45643815292, 30579.694203057683, 31222.06333809989, 31308.530372044894, 34645.307093857475, 34783.652163479055, 26113.869430652725, 29094.709052910046, 29263.122106336843, 30966.380672385974, 31100.66744994089, 25109.87445062763, 25111.49777004486, 25370.619440708775, 25640.871795640902, 25815.225543233733], "value": 19147.55477858165, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.22550252510126, 399.6682753314749, 404.1674151248428, 404.3913909566106, 424.2300225091459, 13536.864631353683, 17578.296868125275, 18295.451136465927, 19115.71326430103, 19204.23183072679, 18735.3442629508, 19086.904565477154, 19147.0, 19417.126229319678, 19571.119299631515, 5737.19679244905, 5755.712214389285, 5925.970370148143, 5949.643021418707, 5957.165996760457, 5803.941960580382, 5916.083007133901, 5923.881522369572, 5924.37794031627, 5926.348101708809, 5693.459121383462, 5792.9283082629845, 5886.676232807194, 5901.409859014086, 5915.112733090049, 5568.415316391761, 5842.88314233715, 5849.415058494159, 5871.031279838843, 5873.588848780587, 5636.633618814805, 5636.971815140939, 5638.718064096779, 5669.376368599451, 5671.489565939085, 12041.0, 12150.0, 21340.893295533424, 22165.00257488401, 22667.77332226673, 2237.79859812616, 2396.6405039244078, 2456.2262887190477, 2621.5805471124527, 2857.1428571428487, 8752.912470875273, 8919.375643704943, 9516.809663806755, 9703.17523010545, 13267.402966866513, 1876.7654043244595, 1889.725989731495, 1899.9525011874675, 1940.4178746376172, 1961.548843765942, 77170.0, 77525.61237193835, 77853.61073194598, 78445.60777196134, 78700.0, 10119.59521619127, 10161.745956351002, 10369.066783989478, 10694.946525267324, 10998.395088270221, 13594.660133496742, 14554.27228638585, 14654.120752754869, 14709.852901470847, 14726.11643301384, 7404.666789994463, 7561.168271490132, 7834.725784597512, 7940.801479963047, 7991.0, 7478.5138965967035, 7502.324790768748, 7686.961565192139, 7890.8816367754525, 7939.166387529342, 4337.609615134653, 4521.59305662492, 4707.7175369477945, 4882.0, 4909.800266707643, 1955.8044195580612, 1978.0, 2040.8367330613514, 2057.084597354163, 2059.4748339173416, 843.4053991935671, 911.8677791720163, 937.7889974755625, 955.1642312976146, 995.9651412200683, 16443.013419194886, 17634.382796602058, 18392.908035459735, 18643.813561864208, 18669.698353754207, 896.2964073202505, 917.8302014127407, 932.0, 941.1529623338913, 956.1155930764015, 9867.210623150275, 10461.0, 10463.68608941733, 10491.370517768959, 10560.52477638508, 412737.0, 415162.0, 421289.0, 423392.0, 424410.0, 82337.58831205805, 84324.0, 85334.0, 86105.56947215224, 86118.0, 946.6024269806697, 951.2627713522076, 957.042957042957, 959.8704174936433, 962.4081190068058, 15562.844371556139, 15771.605709857346, 16064.518064457618, 16321.836781632506, 16499.58751031234, 38277.0, 38699.6130038696, 38813.611863881, 39221.01947451336, 40008.99977500586, 53329.733351334544, 54190.0, 54495.45504544904, 55021.0, 55069.173962389796, 8224.917750822655, 8299.502029878227, 8493.32053435736, 8523.275521580736, 8757.211850933192, 11751.353675547927, 12433.626991190278, 12448.875511244772, 12540.0, 12931.612051638465, 11100.501432306535, 11116.888831111586, 11294.378809165575, 11427.542898283975, 11463.828042579202, 10995.34027958325, 11052.447377631248, 11121.332720036824, 11121.88878111241, 11293.887061129284, 11162.609308674157, 11279.09767218637, 11301.37842418642, 11353.432328383713, 11565.884341156481, 8226.588670566329, 8471.49171049739, 8548.91451085506, 8610.095939926217, 8678.696245631625, 8471.79733269342, 10125.898741012496, 11199.776004479701, 11204.43977801123, 11770.352630605057], "value": 8866.149047426332, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.9930550347246, 1407.9859201407985, 1509.8188217413913, 1712.1353716373246, 1754.175537497376, 7677.232276772327, 8159.959200203991, 8169.305609023225, 8176.754697359074, 8177.509349439038, 7158.427325813936, 7390.297921697444, 7638.54168749874, 7847.960760196205, 7872.134065252818, 2176.4341271269504, 2209.160519002785, 2213.214308920334, 2214.77852214778, 2218.0240694094546, 2186.529896072344, 2192.3642143778284, 2210.4694873230387, 2228.2869481765842, 2257.9887100564474, 2196.494806194576, 2199.7910198531113, 2217.0466699319286, 2223.8109760670363, 2231.364061242546, 2127.904244309002, 2152.2251989283845, 2155.5257843274453, 2160.092761040359, 2164.5346250556127, 2204.7684993075654, 2218.2901471529117, 2221.766714494987, 2222.055626358791, 2224.021430570544, 8068.556229407381, 8079.070906845671, 8143.42995990275, 8144.877826832602, 8182.427230093896, 5083.415407228142, 5223.973880130575, 5224.294720212799, 5228.163493841005, 5233.738313084368, 3187.3107253155863, 3279.360524697685, 3366.848491817874, 3383.5263063171174, 3383.678550537695, 1082.3019152646577, 1091.3015669971226, 1093.6828319787332, 1132.4847194526553, 1177.3406892140429, 10919.0, 10971.945140274327, 11001.944990275077, 11010.0, 11022.228444008922, 4468.687191896536, 4588.541145885385, 4665.790039448233, 4678.789454474557, 4681.765911704401, 9237.168654820964, 9247.861282080774, 9252.7686807829, 9303.441793492411, 9305.441673499612, 5326.973365133227, 5367.221752845816, 5371.597130215249, 5376.623636345496, 5377.112776391929, 4046.2109888571445, 4676.462206846256, 4871.415430148405, 4944.159492886218, 4950.653454258239, 3768.8680896169053, 3827.3876179811095, 3857.498525191707, 3893.785841778673, 3916.060145565101, 1347.595721283611, 1350.8784209421199, 1356.8236129303127, 1360.0411709744606, 1371.1978492581752, 724.9782506524813, 793.4168386236112, 884.9469031858109, 915.8031023329883, 920.6869664314165, 5410.702411367334, 5422.972885135628, 5553.250311208016, 5565.026120428909, 5568.9443105569235, 804.4690504267272, 813.8616435206029, 818.300353198019, 831.1646794971136, 863.576847344809, 3728.105254738897, 3760.4735337052834, 3791.5071040765074, 3808.562015368268, 3888.6305800948867, 9908.108270255567, 10269.435181064964, 10292.948535257425, 10560.366378017343, 10561.735956600993, 10392.168626510012, 10415.583376665, 10418.426986515662, 10441.32131411471, 10537.9473102634, 7586.696532138652, 7740.148583655906, 7746.49647772893, 7750.341220996278, 7763.92236077632, 5491.341039075336, 5527.309086364205, 5588.6646801192055, 5700.771969121354, 5753.2520772301, 9031.232345250464, 9328.39365441244, 9383.202427793714, 9429.57566909477, 9443.858342124737, 9344.392614480308, 9443.291753118407, 9479.952600237231, 9619.855702164614, 9628.9037109628, 6814.4548436124, 6904.102466679299, 7010.754623588354, 7067.434605231672, 7069.787906362817, 7573.848523029398, 7578.962105189439, 7612.5051871630385, 7671.232876712284, 7672.578008209602, 6854.314568543105, 6873.553219040747, 6951.548149370275, 6975.511714179958, 7148.964255178691, 6858.2455929848675, 6919.416180482381, 6992.510524263253, 7041.049458323266, 7041.436685065285, 6086.178365920544, 6117.0824376342125, 6162.845928851814, 6163.322034576283, 6165.32181460048, 4581.702189357681, 4672.415948006499, 4709.976450117864, 4769.070031343855, 4790.832320868893, 4646.907061858811, 4756.048790242035, 4816.145783873164, 4818.349522814376, 4882.853514394574], "value": 4357.530532167912, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.56276001218595, 383.35022137476955, 383.8176865988656, 384.11461581055676, 386.19093000164656, 5139.922901156477, 5191.948080519193, 5198.350206224222, 5201.401838788541, 5215.157739032227, 4884.413870335556, 4971.651984361087, 4988.301637770715, 5062.341895553573, 5129.615278854091, 4100.605794030029, 4104.753714777108, 4111.876643700693, 4124.83500659974, 4126.814293356806, 4101.610347017044, 4115.135821477494, 4133.5659755725665, 4138.461999940018, 4154.480689913761, 3944.152007318426, 3955.6044395560502, 4040.313146765057, 4047.433359329696, 4103.405006274089, 4046.2196633481467, 4047.0, 4058.127502586958, 4058.1883623275166, 4070.715049946505, 3657.9817100914593, 3676.3198808220563, 3684.1342284563107, 3690.4833323334756, 3694.3350196964534, 4710.845842768544, 4914.36113305268, 4939.382577177853, 5008.670967999861, 5021.372328458942, 4599.609033232179, 4633.513481084505, 4693.319468677057, 4696.436427628672, 4711.0, 5278.340207474066, 5320.068987927098, 5323.281357016792, 5328.387235467893, 5356.276902618136, 3963.3856752203455, 3964.6630036446936, 4026.617471340218, 4036.979815100906, 4048.271311163989, 5456.236126942231, 5468.781248750045, 5491.972540137313, 5518.172274158908, 5518.641288316287, 4103.671706263492, 4106.603754723408, 4113.362428823537, 4117.135401565645, 4139.9793001035405, 4152.314868046798, 4152.335626299778, 4183.45615070045, 4206.579342065769, 4214.957850421518, 4343.978280108579, 4562.110388474216, 4573.702709323949, 4584.15193189261, 4601.953980460153, 5383.946160538345, 5408.3509978802795, 5410.269613602113, 5411.2965314508865, 5441.211024401518, 1371.5748118083427, 1374.9656258593616, 1380.7445622559858, 1395.2117053864533, 1396.211140705498, 870.4820631724114, 887.2946007923716, 908.5548081440047, 928.5078908178676, 938.6808485115093, 5049.394072711299, 5087.0, 5092.516210959953, 5110.0, 5135.281060651511, 5354.571634269249, 5359.330083739533, 5363.758630861621, 5368.302120724359, 5390.649607775482, 4823.879403014953, 4952.5542701157065, 5007.47421520735, 5095.490450954949, 5101.923471147936, 2822.788290878192, 2831.830090194595, 2836.3334616365137, 2839.531477306263, 2842.4030953499996, 5425.159100339453, 5531.700050488132, 5604.075327570987, 5634.52106570946, 5645.995671536545, 5351.210666453502, 5420.945790542123, 5421.295231619864, 5425.294711687534, 5448.264484294648, 5530.972345138249, 5546.833594992076, 5561.416051314553, 5567.944320556823, 5606.691631960118, 5065.746712664279, 5284.339457567804, 5333.546648534918, 5337.199420086863, 5398.784048638167, 5283.973580132075, 5290.867728306823, 5300.2049692546425, 5315.202719592092, 5319.654222475527, 5534.225208470737, 5620.522255608319, 5656.915146272891, 5671.092625179951, 5689.317281926195, 3793.7534060285993, 3889.455476233273, 3907.941380879346, 4010.438538604539, 4011.0, 5518.406349015956, 5615.073512870331, 5627.437256274504, 5628.802991895264, 5636.971815140898, 5525.198846167364, 5543.611947163821, 5544.390117087198, 5546.611737178358, 5554.805581804618, 5306.734663266744, 5489.423610520837, 5524.558035357081, 5525.696086715269, 5550.639208451437, 5294.97352513235, 5506.201600767947, 5508.0, 5510.972445137749, 5513.393526711979, 5367.382750983531, 5465.524308436779, 5478.780848766164, 5506.0089183947275, 5510.752016159202, 5352.464753524617, 5356.839294821161, 5357.249985002025, 5366.946330536801, 5385.2191432241325], "value": 4137.1319096052075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.5274896576535, 642.0978525172135, 654.7086546486813, 670.9563878347907, 744.6500144931882, 12941.676458088541, 14233.0, 14300.785488217673, 14942.477013304537, 15003.399864005427, 15663.843361566382, 15957.84042159578, 16057.437989670363, 16193.433229836957, 16211.351545938149, 805.1948051948052, 822.9465084769471, 824.884516167737, 824.905135909369, 825.3397282174267, 553.0653196098581, 571.5627544928134, 698.811320943345, 716.8172116110385, 809.3565615335792, 828.3125006244802, 848.3298194426395, 855.1832999485507, 856.5289090999956, 856.9914300856974, 855.6235256487188, 855.7774978505571, 856.2465030772943, 856.297835774665, 856.4304737349655, 500.9323741294915, 523.8664140644149, 530.1835173832294, 540.956723462126, 629.7355110853453, 11772.2936623802, 11963.1625786195, 12098.697532561668, 12309.0, 12329.568465103679, 12451.0, 12789.872101278961, 12861.485540578366, 12895.16181448203, 12945.87054129456, 12230.571929982409, 12357.546328411385, 12402.81395779064, 12697.873021269854, 12857.292848893307, 4154.729942553724, 4212.578742125794, 4348.652107831397, 4361.629261512775, 4394.0, 79025.60487197584, 79209.0, 79311.60344198241, 80939.59530202311, 81165.0, 10739.946300268604, 10856.240063195659, 10861.945690271497, 10916.235863489479, 11021.228514003942, 17837.197326120357, 17952.461426157235, 17996.460106196573, 18029.27882884696, 18053.187606557738, 7306.890396644055, 7331.303526165006, 7431.925680743231, 7483.363914067365, 7514.135874374408, 4149.792510374469, 4176.582341765799, 4229.894252643709, 4341.934870976938, 4375.190589740908, 3359.328134373135, 3460.8788692395647, 3463.1861512544533, 3464.6535346465657, 3481.721462283011, 569.9202111704365, 577.7400169923551, 583.5098517245449, 597.1997523318687, 599.2688919518181, 528.6854321678595, 553.4520824383831, 588.7586089703201, 643.1863692429081, 660.4716227018366, 8899.866502002475, 8924.241439477717, 9280.953595232115, 9319.767005824908, 9523.714288571355, 511.90529751996, 572.6478215897193, 580.7270582826065, 587.7913340764053, 607.1075518987082, 4432.047109871393, 4482.986406011785, 4641.396618439581, 4662.0, 4715.316279139574, 85056.57471712725, 86879.56560217285, 87652.5617371909, 89402.55298723595, 90806.54596727106, 73504.0, 75864.0, 76330.61834690902, 78628.0, 79380.0, 69742.0, 70139.64930175418, 70671.0, 70752.0, 72381.2761872385, 8712.390132690904, 8841.0, 8943.686970955985, 8944.910550894409, 8990.550472476481, 46478.30282545832, 47399.0, 47563.04873902572, 48616.270755937985, 49299.26051109308, 63464.68267658632, 63973.36026639674, 64416.0, 66766.65566787747, 66853.66573167103, 11491.942540287246, 11529.1929564933, 11604.535818567163, 12220.55888223553, 12602.799411796612, 12202.572909948109, 12977.156484828456, 13602.38789254466, 14182.43270269219, 14368.0, 11516.251443656136, 11935.164538482222, 12791.87208127944, 13264.469421223042, 13265.801012985006, 10135.391876487434, 11684.591039313924, 11786.233894796811, 11941.104417168573, 12252.326122063016, 11689.590864319707, 12097.2136811107, 12152.149349545447, 12313.384330783247, 12601.936990314989, 10125.0, 10498.895011050097, 10517.526711297856, 10622.787544248917, 11323.660290191307, 10371.37793575219, 11092.655951623145, 11212.719682008015, 11318.773624527628, 11353.772924541297], "value": 6920.693459809086, "unit": "score"}], "name": "dromaeo_css", "value": 6948.232236743388, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 114.0, 113.0, 114.0, 111.0, 110.0, 110.0, 112.0, 111.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 122.0, 123.0, 158.0, 122.0, 125.0, 119.0, 119.0, 151.0, 122.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 164.0, 161.0, 161.0, 164.0, 160.0, 161.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 105.0, 77.0, 76.0, 77.0, 75.0, 90.0, 77.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 115.0, 85.0, 85.0, 83.0, 97.0, 87.0, 83.0, 109.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 146.0, 147.0, 149.0, 149.0, 148.0, 147.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 50.0, 53.0, 53.0, 51.0, 51.0, 51.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 66.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 81.0, 81.0, 81.0, 83.0, 86.0, 82.0, 85.0, 81.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 128.0, 127.0, 137.0, 123.0, 125.0, 126.0, 135.0, 172.0, 140.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 172.0, 165.0, 167.0, 168.0, 177.0, 166.0, 170.0, 164.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 62.0, 62.0, 64.0, 65.0, 64.0, 66.0, 68.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1504.5, "alertThreshold": 2.0}]} 17:40:37 INFO - Return code: 0 17:40:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:40:37 INFO - # TBPL SUCCESS # 17:40:37 INFO - Running post-action listener: _resource_record_post_action 17:40:37 INFO - [mozharness: 2016-12-23 01:40:37.170811Z] Finished run-tests step (success) 17:40:37 INFO - Running post-run listener: _resource_record_post_run 17:40:37 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 419979264; Read time: 4; Write time: 308900 17:40:37 INFO - TinderboxPrint: CPU usage
13.1% 17:40:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 17:40:37 INFO - TinderboxPrint: I/O write bytes / time
419,979,264 / 308,900 17:40:37 INFO - TinderboxPrint: CPU idle
8,273.6 (86.9%) 17:40:37 INFO - TinderboxPrint: CPU user
1,199.4 (12.6%) 17:40:37 INFO - TinderboxPrint: Swap in / out
0 / 0 17:40:37 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 122531840; Read time: 0; Write time: 95568 17:40:37 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 297447424; Read time: 0; Write time: 213332 17:40:37 INFO - Running post-run listener: _upload_blobber_files 17:40:37 INFO - Blob upload gear active. 17:40:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:40:37 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 17:40:37 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:40:37 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:40:38 INFO - (blobuploader) - INFO - Open directory for files ... 17:40:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:40:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:40:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:39 INFO - (blobuploader) - INFO - Done attempting. 17:40:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:40:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:40:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:43 INFO - (blobuploader) - INFO - Done attempting. 17:40:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:40:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:40:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:43 INFO - (blobuploader) - INFO - Done attempting. 17:40:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:40:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:40:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:44 INFO - (blobuploader) - INFO - Done attempting. 17:40:44 INFO - (blobuploader) - INFO - Iteration through files over. 17:40:44 INFO - Return code: 0 17:40:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:40:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:40:44 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/061e964a4691004501c71e6a70e9810bbe6fdf7dbee0ce98a6c482e7238d0b600f1faae64a669c44c147607d0599524fa9432ee1554be883d3b9667da4ce5302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4aa39845c4b83bdbb04b0380d4049e8015c3d1fc0bbee1e37baf9c6a03924aa49be5b552ffc1aae2b1838b8bc37bd5bf570a2480d79df55ceb60eaec72317e5e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e675b4507e90b74a325b543ab484c3f3273844f540e12c1eb3e498cde59359635934e508061a30d0d6efc0ea9c2a83894d67791e6a273a1348fc3634493eca81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e03fbf42d3d4b8b592dbfe10307c35a5672fa0b9cb2e39e81fa798464a617d9e8d17f3c8d919070ecd4e8994aeb1e4e23a7de1969c3904c370e134804c132ead"} 17:40:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:40:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:40:44 INFO - Contents: 17:40:44 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/061e964a4691004501c71e6a70e9810bbe6fdf7dbee0ce98a6c482e7238d0b600f1faae64a669c44c147607d0599524fa9432ee1554be883d3b9667da4ce5302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4aa39845c4b83bdbb04b0380d4049e8015c3d1fc0bbee1e37baf9c6a03924aa49be5b552ffc1aae2b1838b8bc37bd5bf570a2480d79df55ceb60eaec72317e5e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e675b4507e90b74a325b543ab484c3f3273844f540e12c1eb3e498cde59359635934e508061a30d0d6efc0ea9c2a83894d67791e6a273a1348fc3634493eca81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e03fbf42d3d4b8b592dbfe10307c35a5672fa0b9cb2e39e81fa798464a617d9e8d17f3c8d919070ecd4e8994aeb1e4e23a7de1969c3904c370e134804c132ead"} 17:40:44 INFO - Running post-run listener: copy_logs_to_upload_dir 17:40:44 INFO - Copying logs to upload dir... 17:40:44 INFO - mkdir: /builds/slave/test/build/upload/logs 17:40:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.455291 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-12-22 17:40:44.332189) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 17:40:44.335841) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/061e964a4691004501c71e6a70e9810bbe6fdf7dbee0ce98a6c482e7238d0b600f1faae64a669c44c147607d0599524fa9432ee1554be883d3b9667da4ce5302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4aa39845c4b83bdbb04b0380d4049e8015c3d1fc0bbee1e37baf9c6a03924aa49be5b552ffc1aae2b1838b8bc37bd5bf570a2480d79df55ceb60eaec72317e5e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e675b4507e90b74a325b543ab484c3f3273844f540e12c1eb3e498cde59359635934e508061a30d0d6efc0ea9c2a83894d67791e6a273a1348fc3634493eca81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e03fbf42d3d4b8b592dbfe10307c35a5672fa0b9cb2e39e81fa798464a617d9e8d17f3c8d919070ecd4e8994aeb1e4e23a7de1969c3904c370e134804c132ead"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011714 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1482449665/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/061e964a4691004501c71e6a70e9810bbe6fdf7dbee0ce98a6c482e7238d0b600f1faae64a669c44c147607d0599524fa9432ee1554be883d3b9667da4ce5302", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4aa39845c4b83bdbb04b0380d4049e8015c3d1fc0bbee1e37baf9c6a03924aa49be5b552ffc1aae2b1838b8bc37bd5bf570a2480d79df55ceb60eaec72317e5e", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e675b4507e90b74a325b543ab484c3f3273844f540e12c1eb3e498cde59359635934e508061a30d0d6efc0ea9c2a83894d67791e6a273a1348fc3634493eca81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e03fbf42d3d4b8b592dbfe10307c35a5672fa0b9cb2e39e81fa798464a617d9e8d17f3c8d919070ecd4e8994aeb1e4e23a7de1969c3904c370e134804c132ead"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 17:40:44.361655) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 17:40:44.361993) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1482447453.544179-820448583 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004427 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 17:40:44.447295) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 17:40:44.447628) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 17:40:44.447918) ========= ========= Total master_lag: 0.17 =========