Package: src:linux Version: 4.19.118-2 Severity: normal Dear Maintainer,
I have a Qnap TS-112 with Debian installed on it. I very rarely need to reboot it, but when I do, the systemd journal looks more messed up every time. I think entries from different boots are being interleaved, and 'journalctl --list-boots' does not show all boots. I have observed that _BOOT_ID recorded in the messages does not seem to change. From that, I assume that the kernel boot_id does not change as it should either. This machine does not have a battery-backed RTC. I'm not quite sure if this problem has always existed or is it a regression. I have seen journal message ordering problems before, but I thought those were directly caused by the lack of RTC plus me using ntpd which does not initialize early enough in boot. I've switched to systemd's own NTP time keeping since then quite a while ago. I purposefully rebooted the machine with 'shutdown -r now', and after it restarted, there was no new boot entry in --list-boots. I do use persistent storage for the journal, and I do see all the messages from earlier boots as well as some messages from "right now". -- Package-specific info: ** Version: Linux version 4.19.0-9-marvell (debian-ker...@lists.debian.org) (gcc version 8.3.0 (Debian 8.3.0-6)) #1 Debian 4.19.118-2 (2020-04-29) ** Command line: console=ttyS0,115200 root=/dev/ram initrd=0xa00000,0x900000 ramdisk=34816 ** Not tainted ** Kernel log: [ 1.327639] Loaded X.509 cert 'Debian Secure Boot Signer: 00a7468def' [ 1.334222] AppArmor: AppArmor sha1 policy hashing enabled [ 1.341049] hctosys: unable to open rtc device (rtc0) [ 1.348805] Freeing unused kernel memory: 316K [ 1.353291] This architecture does not have kernel memory protection. [ 1.359772] Run /init as init process [ 1.800780] rtc-s35390a 0-0030: rtc core: registered rtc-s35390a as rtc0 [ 1.879332] SCSI subsystem initialized [ 1.939565] libata version 3.00 loaded. [ 1.941963] sata_mv f1080000.sata: version 1.28 [ 1.942218] sata_mv f1080000.sata: slots 32 ports 2 [ 1.956601] scsi host0: sata_mv [ 1.962267] scsi host1: sata_mv [ 1.966421] ata1: SATA max UDMA/133 irq 35 [ 1.970565] ata2: SATA max UDMA/133 irq 35 [ 2.447840] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl F300) [ 2.458677] ata1.00: ATA-8: WDC WD2500AAJS-00L7A0, 01.03E01, max UDMA/133 [ 2.465522] ata1.00: 488397168 sectors, multi 0: LBA48 NCQ (depth 31/32) [ 2.476909] ata1.00: configured for UDMA/133 [ 2.481535] scsi 0:0:0:0: Direct-Access ATA WDC WD2500AAJS-0 3E01 PQ: 0 ANSI: 5 [ 2.806149] ata2: SATA link down (SStatus 0 SControl F300) [ 2.837515] sd 0:0:0:0: [sda] 488397168 512-byte logical blocks: (250 GB/233 GiB) [ 2.849404] sd 0:0:0:0: [sda] Write Protect is off [ 2.854258] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 2.854349] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 2.902094] sda: sda1 sda2 sda3 < sda5 sda6 > [ 2.910970] sd 0:0:0:0: [sda] Attached SCSI disk [ 3.769581] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null) [ 5.003150] systemd[1]: System time before build time, advancing clock. [ 5.191939] systemd[1]: Inserted module 'autofs4' [ 5.277439] NET: Registered protocol family 10 [ 5.449316] Segment Routing with IPv6 [ 5.453864] random: crng init done [ 5.552715] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) [ 5.574721] systemd[1]: Detected architecture arm. [ 5.642395] systemd[1]: Set hostname to <ashur>. [ 7.195905] systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. [ 7.213074] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) [ 7.979648] systemd[1]: /lib/systemd/system/dovecot.service:9: PIDFile= references path below legacy directory /var/run/, updating /var/run/dovecot/master.pid \xe2\x86\x92 /run/dovecot/master.pid; please update the unit file accordingly. [ 8.081041] systemd[1]: Listening on fsck to fsckd communication Socket. [ 8.104877] systemd[1]: Listening on Journal Socket. [ 8.126584] systemd[1]: Created slice User and Session Slice. [ 8.148034] systemd[1]: Reached target Slices. [ 8.789595] input: gpio_keys as /devices/platform/gpio_keys/input/input0 [ 8.864058] EXT4-fs (sda2): re-mounted. Opts: errors=remount-ro [ 9.660619] systemd-journald[141]: Received request to flush runtime journal from PID 1 [ 9.697129] systemd-journald[141]: File /var/log/journal/0c4d9c82740545bfaeedcc433021e0d3/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 11.106934] orion_wdt: Initial timeout 21 sec [ 11.117987] libphy: orion_mdio_bus: probed [ 11.124844] mv643xx_eth: MV-643xx 10/100/1000 ethernet driver version 1.4 [ 11.173144] mv643xx_eth_port mv643xx_eth_port.0: DMA mask not set [ 11.184098] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 11.276966] marvell-cesa f1030000.crypto: CESA device successfully registered [ 11.333928] usbcore: registered new interface driver usbfs [ 11.405865] usbcore: registered new interface driver hub [ 11.454286] usbcore: registered new device driver usb [ 11.514655] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 11.529407] m25p80 spi0.0: m25p128 (16384 Kbytes) [ 11.612793] ehci-orion: EHCI orion driver [ 11.651274] orion-ehci f1050000.ehci: EHCI Host Controller [ 11.690986] orion-ehci f1050000.ehci: new USB bus registered, assigned bus number 1 [ 11.748284] orion-ehci f1050000.ehci: irq 32, io mem 0xf1050000 [ 11.780401] 6 fixed-partitions partitions found on MTD device spi0.0 [ 11.786887] Creating 6 MTD partitions on "spi0.0": [ 11.798602] orion-ehci f1050000.ehci: USB 2.0 started, EHCI 1.00 [ 11.819221] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 [ 11.827745] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 11.835192] usb usb1: Product: EHCI Host Controller [ 11.840115] usb usb1: Manufacturer: Linux 4.19.0-9-marvell ehci_hcd [ 11.846591] usb usb1: SerialNumber: f1050000.ehci [ 11.875921] 0x000000000000-0x000000080000 : "U-Boot" [ 11.889357] 0x000000200000-0x000000400000 : "Kernel" [ 11.901352] 0x000000400000-0x000000d00000 : "RootFS1" [ 11.908440] rtc-mv f1010300.rtc: internal RTC not ticking [ 11.942660] 0x000000d00000-0x000001000000 : "RootFS2" [ 11.955870] 0x000000080000-0x0000000c0000 : "U-Boot Config" [ 11.980736] hub 1-0:1.0: USB hub found [ 11.991747] 0x0000000c0000-0x000000200000 : "NAS Config" [ 12.000489] hub 1-0:1.0: 1 port detected [ 12.076662] mv643xx_eth_port mv643xx_eth_port.0 eth0: port 0 with MAC address 00:08:9b:c2:22:da [ 12.395921] usb 1-1: new high-speed USB device number 2 using orion-ehci [ 12.573677] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608, bcdDevice=77.63 [ 12.581929] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 [ 12.589137] usb 1-1: Product: USB2.0 Hub [ 12.620219] hub 1-1:1.0: USB hub found [ 12.630814] hub 1-1:1.0: 4 ports detected [ 13.292132] Adding 740348k swap on /dev/sda5. Priority:-2 extents:1 across:740348k FS [ 15.138366] EXT4-fs (sda1): mounting ext2 file system using the ext4 subsystem [ 15.208556] EXT4-fs (sda1): mounted filesystem without journal. Opts: (null) [ 26.349485] EXT4-fs (sda6): mounted filesystem with ordered data mode. Opts: (null) [ 26.990202] audit: type=1400 audit(1550139139.984:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=215 comm="apparmor_parser" [ 27.039871] audit: type=1400 audit(1550139139.984:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=215 comm="apparmor_parser" [ 27.099893] audit: type=1400 audit(1550139139.984:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_groff" pid=215 comm="apparmor_parser" [ 27.167913] audit: type=1400 audit(1550139140.112:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/ntpd" pid=218 comm="apparmor_parser" [ 27.247924] audit: type=1400 audit(1550139140.144:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe" pid=219 comm="apparmor_parser" [ 27.339167] audit: type=1400 audit(1550139140.144:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe//kmod" pid=219 comm="apparmor_parser" [ 28.972617] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 29.481154] NET: Registered protocol family 17 [ 30.564670] mv643xx_eth_port mv643xx_eth_port.0 eth0: link up, 100 Mb/s, full duplex, flow control disabled [ 30.574589] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready ** Model information Hardware : Marvell Kirkwood (Flattened Device Tree) Revision : 0000 Device Tree model: QNAP TS219 family ** Loaded modules: af_packet ofpart cmdlinepart ehci_orion m25p80 ehci_hcd spi_nor mtd marvell usbcore usb_common nls_base marvell_cesa sg mv643xx_eth mvmdio orion_wdt des_generic spi_orion evdev gpio_keys ip_tables x_tables sha256_generic hmac ipv6 autofs4 ext4 crc16 mbcache jbd2 crc32c_generic fscrypto ecb sd_mod sata_mv libata scsi_mod rtc_s35390a ** PCI devices: 00:01.0 PCI bridge [0604]: Marvell Technology Group Ltd. 88F6281 [Kirkwood] ARM SoC [11ab:6281] (rev 03) (prog-if 00 [Normal decode]) Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr+ Stepping- SERR+ FastB2B- DisINTx- Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx- Bus: primary=00, secondary=01, subordinate=01, sec-latency=0 Prefetchable memory behind bridge: 00000000-000fffff Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR- BridgeCtl: Parity- SERR- NoISA- VGA- MAbort- >Reset- FastB2B- PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn- Capabilities: <access denied> ** USB devices: Bus 001 Device 002: ID 05e3:0608 Genesys Logic, Inc. Hub Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub -- System Information: Debian Release: 10.4 APT prefers stable APT policy: (500, 'stable') Architecture: armel (armv5tel) Kernel: Linux 4.19.0-9-marvell Locale: LANG=en_GB.UTF-8, LC_CTYPE=en_GB.UTF-8 (charmap=UTF-8), LANGUAGE=en_US:en (charmap=UTF-8) Shell: /bin/sh linked to /bin/dash Init: systemd (via /run/systemd/system) LSM: AppArmor: enabled Versions of packages linux-image-4.19.0-9-marvell depends on: ii initramfs-tools [linux-initramfs-tool] 0.133+deb10u1 ii kmod 26-1 ii linux-base 4.6 Versions of packages linux-image-4.19.0-9-marvell recommends: ii apparmor 2.13.2-10 ii firmware-linux-free 3.4 ii u-boot-tools 2019.01+dfsg-7 Versions of packages linux-image-4.19.0-9-marvell suggests: pn debian-kernel-handbook <none> pn linux-doc-4.19 <none> Versions of packages linux-image-4.19.0-9-marvell is related to: pn firmware-amd-graphics <none> pn firmware-atheros <none> pn firmware-bnx2 <none> pn firmware-bnx2x <none> pn firmware-brcm80211 <none> pn firmware-cavium <none> pn firmware-intel-sound <none> pn firmware-intelwimax <none> pn firmware-ipw2x00 <none> pn firmware-ivtv <none> pn firmware-iwlwifi <none> pn firmware-libertas <none> pn firmware-linux-nonfree <none> pn firmware-misc-nonfree <none> pn firmware-myricom <none> pn firmware-netxen <none> pn firmware-qlogic <none> pn firmware-realtek <none> pn firmware-samsung <none> pn firmware-siano <none> pn firmware-ti-connectivity <none> pn xen-hypervisor <none> -- no debconf information