New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 611836 link

Starred by 1 user

Issue metadata

Status: Archived
Owner: ----
Closed: Jan 10
Cc:
EstimatedDays: ----
NextAction: ----
OS: ----
Pri: 3
Type: Bug



Sign in to add a comment

jetstream_DiagnosticReport test failed with message 'End marker not in /var/log/messages'

Project Member Reported by shuqianz@chromium.org, May 13 2016

Issue description

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/
 
Project Member

Comment 1 by sheriffbot@chromium.org, Sep 18 2017

Labels: Hotlist-Recharge-BouncingOwner
Owner: ----
Status: Untriaged (was: Assigned)
The assigned owner "kemp@chromium.org" is not able to receive e-mails, please re-triage.

For more details visit https://www.chromium.org/issue-tracking/autotriage - Your friendly Sheriffbot
Status: Archived (was: Untriaged)
Archiving P3s older than 1 year with no owner or component.

Sign in to add a comment