2023-09-23 18:17:04 +02:00
#!/usr/bin/env bash
# SPDX-License-Identifier: LGPL-2.1-or-later
set -eux
set -o pipefail
# shellcheck source=test/units/util.sh
. " $( dirname " $0 " ) " /util.sh
get_first_boot_id( ) {
2023-09-29 23:26:15 +02:00
journalctl -b " ${ 1 : ? } " -o json -n +1 | jq -r '._BOOT_ID'
2023-09-23 18:17:04 +02:00
}
get_last_boot_id( ) {
journalctl -b " ${ 1 : ? } " -o json -n 1 | jq -r '._BOOT_ID'
}
get_first_timestamp( ) {
2023-09-29 23:26:15 +02:00
journalctl -b " ${ 1 : ? } " -o json -n +1 | jq -r '.__REALTIME_TIMESTAMP'
2023-09-23 18:17:04 +02:00
}
get_last_timestamp( ) {
journalctl -b " ${ 1 : ? } " -o json -n 1 | jq -r '.__REALTIME_TIMESTAMP'
}
2024-05-19 07:12:48 +09:00
# There may be huge amount of pending messages in sockets. Processing them may cause journal rotation.
# If the journal is rotated in the loop below, some journal file may not be loaded and an unexpected
2024-05-22 09:20:00 +09:00
# result may be provided. To mitigate such, flush (if not yet) and sync before reading journals.
# Workaround for #32890.
journalctl --flush
2024-05-19 07:12:48 +09:00
journalctl --sync
2024-05-22 09:20:00 +09:00
# Sometimes, loading partially written .journal file, and journalctl handled that as 'truncated':
# ===
# May 21 02:25:55 TEST-09-REBOOT.sh[433]: + journalctl --list-boots -o json
# May 21 02:25:55 journalctl[433]: Journal file /var/log/journal/173da2fad3064e3e9211a7ed7d59360b/system.journal is truncated, ignoring file.
# ===
# If that happens, the entries stored in the journal file are ignored, and the results of --list-boots
# and subsequent call of journalctl may become inconsistent. To prevent such issue, let's also rotate
# the journal. Then, all journal entries we are interested in are stored in the archived journal files.
journalctl --rotate
2024-05-19 07:12:48 +09:00
2023-09-23 18:17:04 +02:00
# Issue: #29275, second part
# Now let's check if the boot entries are in the correct/expected order
index = 0
SYSTEMD_LOG_LEVEL = debug journalctl --list-boots
journalctl --list-boots -o json | jq -r '.[] | [.index, .boot_id, .first_entry, .last_entry] | @tsv' |
while read -r offset boot_id first_ts last_ts; do
: " Boot # $(( + + index)) ( $offset ) with ID $boot_id "
# Try the "regular" (non-json) variants first, as they provide a helpful
# error message if something is not right
SYSTEMD_LOG_LEVEL = debug journalctl -q -n 0 -b " $index "
SYSTEMD_LOG_LEVEL = debug journalctl -q -n 0 -b " $offset "
SYSTEMD_LOG_LEVEL = debug journalctl -q -n 0 -b " $boot_id "
# Check the boot ID of the first entry
entry_boot_id = " $( get_first_boot_id " $index " ) "
assert_eq " $entry_boot_id " " $boot_id "
entry_boot_id = " $( get_first_boot_id " $offset " ) "
assert_eq " $entry_boot_id " " $boot_id "
entry_boot_id = " $( get_first_boot_id " $boot_id " ) "
assert_eq " $entry_boot_id " " $boot_id "
# Check the timestamp of the first entry
entry_ts = " $( get_first_timestamp " $index " ) "
assert_eq " $entry_ts " " $first_ts "
entry_ts = " $( get_first_timestamp " $offset " ) "
assert_eq " $entry_ts " " $first_ts "
entry_ts = " $( get_first_timestamp " $boot_id " ) "
assert_eq " $entry_ts " " $first_ts "
# Check the boot ID of the last entry
entry_boot_id = " $( get_last_boot_id " $index " ) "
assert_eq " $entry_boot_id " " $boot_id "
entry_boot_id = " $( get_last_boot_id " $offset " ) "
assert_eq " $entry_boot_id " " $boot_id "
entry_boot_id = " $( get_last_boot_id " $boot_id " ) "
assert_eq " $entry_boot_id " " $boot_id "
# Check the timestamp of the last entry
if [ [ " $offset " != "0" ] ] ; then
entry_ts = " $( get_last_timestamp " $index " ) "
assert_eq " $entry_ts " " $last_ts "
entry_ts = " $( get_last_timestamp " $offset " ) "
assert_eq " $entry_ts " " $last_ts "
entry_ts = " $( get_last_timestamp " $boot_id " ) "
assert_eq " $entry_ts " " $last_ts "
fi
done
2023-12-01 12:29:11 +09:00
verify_seqnum( ) {
if [ [ " $REBOOT_COUNT " -ne " $NUM_REBOOT " ] ] ; then
return 0
fi
journalctl --flush
journalctl --sync
ls -lR /var/log/journal/
ls -lR /run/log/journal/
journalctl --system --header
2024-04-29 14:28:32 +02:00
( ! journalctl --system -q -o short-monotonic -u systemd-journald.service --grep 'Journal file uses a different sequence number ID, rotating' )
2023-12-01 12:29:11 +09:00
set +x
previous_seqnum = 0
previous_seqnum_id =
previous_boot_id =
journalctl --system -q -o json | jq -r '[.__SEQNUM, .__SEQNUM_ID, ._BOOT_ID] | @tsv' |
while read -r seqnum seqnum_id boot_id; do
if [ [ -n " $previous_seqnum_id " ] ] ; then
if ! test " $seqnum " -gt " $previous_seqnum " ; then
echo " seqnum= $seqnum is not greater than previous_seqnum= $previous_seqnum "
echo " seqnum_id= $seqnum_id , previous_seqnum_id= $previous_seqnum_id "
echo " boot_id= $boot_id , previous_boot_id= $previous_boot_id "
return 1
fi
assert_eq " $seqnum_id " " $previous_seqnum_id "
fi
previous_seqnum = " $seqnum "
previous_seqnum_id = " $seqnum_id "
previous_boot_id = " $boot_id "
done
set -x
return 0
}
verify_seqnum