jetstream_DiagnosticReport test failed on whirlwind-paladin with error message 'End marker not in /var/log/messages'. https://uberchromegw.corp.google.com/i/chromeos/builders/whirlwind-paladin/builds/4630/steps/HWTest%20%5Bjetstream_cq%5D/logs/stdio
Found the logs on the jetstream_DiagnosticReport debug file:
04/22 21:34:03.672 INFO | client_test:0023| Commandline arguments: {}
04/22 21:34:03.677 DEBUG| base_utils:0176| Running 'ip addr show wlan-2400mhz 2> /dev/null'
04/22 21:34:03.729 DEBUG| base_utils:0176| Running 'ip addr show wlan-5000mhz 2> /dev/null'
04/22 21:34:03.753 DEBUG| base_utils:0176| Running 'find /sys/class/net -type l | while read name; do echo $(basename $name) $(readlink -m $name); done'
04/22 21:34:03.995 DEBUG| base_utils:0176| Running 'iptables -w -S'
04/22 21:34:04.037 DEBUG| base_utils:0176| Running 'iptables -w -A INPUT -i eth0 -j ACCEPT'
04/22 21:34:04.065 INFO | base_router:0222| Configuring AP: --factory_reset
04/22 21:34:04.069 DEBUG| base_utils:0176| Running 'ap-configure --factory_reset'
04/22 21:34:15.148 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:16.157 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:17.162 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:18.172 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:19.184 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:20.195 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:21.202 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:22.208 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:23.217 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:24.226 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:25.237 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:26.245 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:27.251 DEBUG| base_router:0256| Required AP processes are not yet on dbus
04/22 21:34:28.259 DEBUG| base_utils:0176| Running 'iw wlan-2400mhz info'
04/22 21:34:28.311 DEBUG| base_utils:0176| Running 'iw wlan-5000mhz info'
04/22 21:34:28.345 DEBUG| base_router:0244| 2.4Ghz channel: 6, 5Ghz channel: 149
04/22 21:34:28.347 DEBUG| base_utils:0176| Running 'find /sys/class/net -type l | while read name; do echo $(basename $name) $(readlink -m $name); done'
04/22 21:34:28.548 DEBUG| base_utils:0176| Running 'iptables -w -S'
04/22 21:34:28.590 INFO | base_router:0237| Fake factory reset appears complete
04/22 21:34:28.735 ERROR| log:0072| pre-test siteration sysinfo error:
04/22 21:34:28.754 ERROR| traceback:0013| Traceback (most recent call last):
04/22 21:34:28.772 ERROR| traceback:0013| File "/usr/local/autotest/common_lib/log.py", line 70, in decorated_func
04/22 21:34:28.791 ERROR| traceback:0013| fn(*args, **dargs)
04/22 21:34:28.813 ERROR| traceback:0013| File "/usr/local/autotest/bin/base_sysinfo.py", line 379, in log_before_each_iteration
04/22 21:34:28.833 ERROR| traceback:0013| board = utils.get_board_with_frequency_and_memory()
04/22 21:34:28.858 ERROR| traceback:0013| File "/usr/local/autotest/bin/site_utils.py", line 871, in get_board_with_frequency_and_memory
04/22 21:34:28.882 ERROR| traceback:0013| memory = int(round(get_mem_total() / 1024.0))
04/22 21:34:28.903 ERROR| traceback:0013| File "/usr/local/autotest/bin/site_utils.py", line 884, in get_mem_total
04/22 21:34:28.919 ERROR| traceback:0013| assert mem_total > 1024 * 1024, 'Unreasonable amount of memory.'
04/22 21:34:28.936 ERROR| traceback:0013| AssertionError: Unreasonable amount of memory.
04/22 21:34:28.951 INFO | utils:0095| ---- Test started (jetstream_DiagnosticReport) ----
04/22 21:34:28.954 DEBUG| base_utils:0176| Running 'if test -d /var/spool/crash/; then find /var/spool/crash/ -name "*.dmp"; fi'
04/22 21:34:34.162 WARNI|jetstream_Diagnost:0064| Truncated log to purge diagnostic report filler
04/22 21:34:34.186 DEBUG| base_utils:0176| Running 'if test -d /var/spool/crash/; then find /var/spool/crash/ -name "*.dmp"; fi'
04/22 21:34:34.229 WARNI| utils:0102| Failure: End marker not in /var/log/messages
04/22 21:34:34.255 INFO | utils:0107| ---- Test finished (jetstream_DiagnosticReport) ----
04/22 21:34:34.259 DEBUG| base_utils:0176| Running 'mkdir -p /usr/local/autotest/results/default/jetstream_DiagnosticReport/sysinfo/iteration.1/var/spool'
04/22 21:34:34.292 DEBUG| base_utils:0176| Running 'rsync --no-perms --chmod=ugo+r -a --exclude=autoserv* --exclude=*.core /var/spool/crash /usr/local/autotest/results/default/jetstream_DiagnosticReport/sysinfo/iteration.1/var/spool'
04/22 21:34:34.371 DEBUG| base_utils:0176| Running 'rm -rf /var/spool/crash/*'
04/22 21:34:34.411 DEBUG| base_utils:0176| Running 'logger "autotest finished iteration /usr/local/autotest/results/default/jetstream_DiagnosticReport/sysinfo/iteration.1"'
04/22 21:34:34.452 WARNI| test:0606| Autotest caught exception when running test:
Traceback (most recent call last):
File "/usr/local/autotest/common_lib/test.py", line 600, in _exec
_call_test_function(self.execute, *p_args, **p_dargs)
File "/usr/local/autotest/common_lib/test.py", line 804, in _call_test_function
return func(*args, **dargs)
File "/usr/local/autotest/common_lib/test.py", line 461, in execute
dargs)
File "/usr/local/autotest/common_lib/test.py", line 347, in _call_run_once_with_retry
postprocess_profiled_run, args, dargs)
File "/usr/local/autotest/common_lib/test.py", line 376, in _call_run_once
self.run_once(*args, **dargs)
File "/usr/local/autotest/tests/jetstream_DiagnosticReport/jetstream_DiagnosticReport.py", line 77, in run_once
self._TestMessages()
File "/usr/local/autotest/tests/jetstream_DiagnosticReport/jetstream_DiagnosticReport.py", line 74, in _TestMessages
raise error.TestFail('End marker not in %s' % MESSAGES_PATH)
TestFail: End marker not in /var/log/messages
https://pantheon.corp.google.com/storage/browser/chromeos-autotest-results/63139627-chromeos-test/chromeos4-row10-rack12-host5/jetstream_DiagnosticReport/debug/
Comment 1 by sheriffbot@chromium.org
, Sep 18 2017Owner: ----
Status: Untriaged (was: Assigned)