-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1
Hi,
I was working at my desktop machine. My little server machine, media
server, was playing the internet radio with Kodi. I went to the kitchen to
put the dinner on the oven. On return, the media server had rebooted and
was asking for login.
There is no trace in the syslog:
<1.5> 2021-01-23T21:15:03.643324+01:00 Isengard Sistema - - - Logging the current external IP= (omitted)
<3.6> 2021-01-23T21:16:56.732717+01:00 Isengard systemd 1 - - Started Daily Cleanup of Snapper Snapshots.
<3.6> 2021-01-23T21:16:56.873624+01:00 Isengard dbus-daemon 1109 - - [system] Activating service name='org.opensuse.Snapper' requested by ':1.1048'
(uid=0 pid=5447 comm="/usr/lib/snapper/systemd-helper --cleanup ") (using servicehelper)
<3.6> 2021-01-23T21:16:57.131185+01:00 Isengard dbus-daemon 1109 - - [system] Successfully activated service 'org.opensuse.Snapper'
<3.6> 2021-01-23T21:21:56.728956+01:00 Isengard systemd 1 - - Starting Cleanup of Temporary Directories...
<3.5> 2021-01-23T21:21:56.875752+01:00 Isengard systemd-tmpfiles 5561 - - [/usr/lib/tmpfiles.d/net-snmp.conf:1] Line references path below legacy directory /var/run/, updating /var/run/net-snmp → /run/net-snmp; please update the tmpfiles.d/ drop-in file accordingly.
<3.5> 2021-01-23T21:21:56.879004+01:00 Isengard systemd-tmpfiles 5561 - - [/usr/lib/tmpfiles.d/radvd.conf:1] Line references path below legacy directory /var/run/, updating /var/run/radvd → /run/radvd; please update the tmpfiles.d/ drop-in file accordingly.
<3.5> 2021-01-23T21:21:56.879901+01:00 Isengard systemd-tmpfiles 5561 - - [/usr/lib/tmpfiles.d/samba.conf:1] Line references path below legacy directory /var/run/, updating /var/run/samba → /run/samba; please update the tmpfiles.d/ drop-in file accordingly.
<3.5> 2021-01-23T21:21:56.883365+01:00 Isengard systemd-tmpfiles 5561 - - [/usr/lib/tmpfiles.d/svnserve.conf:1] Line references path below legacy directory /var/run/, updating /var/run/svnserve → /run/svnserve; please update the tmpfiles.d/ drop-in file accordingly.
<3.5> 2021-01-23T21:21:56.887213+01:00 Isengard systemd-tmpfiles 5561 - - [/usr/lib/tmpfiles.d/tmp.conf:13] Duplicate line for path "/var/tmp", ignoring.
<3.5> 2021-01-23T21:21:56.888626+01:00 Isengard systemd-tmpfiles 5561 - - [/usr/lib/tmpfiles.d/var.conf:21] Duplicate line for path "/var/lib", ignoring.
<3.5> 2021-01-23T21:21:56.889240+01:00 Isengard systemd-tmpfiles 5561 - - [/usr/lib/tmpfiles.d/var.conf:23] Duplicate line for path "/var/spool", ignoring.
<3.6> 2021-01-23T21:21:56.965040+01:00 Isengard systemd 1 - - Started Cleanup of Temporary Directories.
<1.5> 2021-01-23T21:30:03.251921+01:00 Isengard Sistema - - - Logging the current external IP= (omitted)
<3.6> 2021-01-23T21:33:54.528052+01:00 Isengard smartd 1103 - - Device: /dev/sda [SAT], SMART Usage Attribute: 189 Airflow_Temperature_Cel changed from 41 to 46
<3.6> 2021-01-23T21:33:54.529239+01:00 Isengard smartd 1103 - - Device: /dev/sda [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 41 to 46
<3.6> 2021-01-23T21:33:54.603175+01:00 Isengard smartd 1103 - - Device: /dev/disk/by-id/wwn-0x5000c5009399305f [SAT], SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 66 to 65
<3.6> 2021-01-23T21:33:54.603840+01:00 Isengard smartd 1103 - - Device: /dev/disk/by-id/wwn-0x5000c5009399305f [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 34 to 35
<1.5> 2021-01-23T21:45:03.043504+01:00 Isengard Sistema - - - Logging the current external IP= (omitted)
2021-01-23 22:06:23+01:00 - Booting the system now ================================================================================ Linux Isengard 5.3.18-lp152.60-default #1 SMP Tue Jan 12 23:10:31 UTC 2021 (9898712) x86_64 x86_64 x86_64 GNU/Linux
<3.6> 2021-01-23T22:06:24.780509+01:00 Isengard systemd 1 - - systemd 234 running in system mode. (+PAM -AUDIT +SELINUX -IMA +APPARMOR -SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 -IDN default-hierarchy=hybrid)
<0.6> 2021-01-23T22:06:24.780976+01:00 Isengard kernel - - - [ 0.000000] microcode: microcode updated early to revision 0x411, date = 2019-04-23
<0.5> 2021-01-23T22:06:24.781721+01:00 Isengard kernel - - - [ 0.000000] Linux version 5.3.18-lp152.60-default (geeko@buildhost) (gcc version 7.5.
...
Aside note:
Kernel boot information is missing from syslog. New bug? Compare with leap 15.1 syslog:
2020-09-24 13:57:39+02:00 - Halting the system now =========================================== uptime: 13:57:39 up 82 days 15:43, 0 users, load average: 1.20, 1.30, 1.47
2020-09-24 20:48:11+02:00 - Booting the system now ================================================================================ Linux Isengard 4.12.14-lp151.28.67-default #1 SMP Fri Sep 4 15:23:21 UTC 2020 (2c5a14f) x86_64 x86_64 x86_64 GNU/Linux
<0.6> 2020-09-24T20:48:12.560421+02:00 Isengard kernel - - - [ 0.000000] microcode: microcode updated early to revision 0x411, date = 2019-04-23
<0.5> 2020-09-24T20:48:12.560854+02:00 Isengard kernel - - - [ 0.000000] Linux version 4.12.14-lp151.28.67-default (geeko@buildhost) (gcc version 7.5.0 (SUSE Linux) ) #1 SMP Fri Sep 4 15:23:21 UTC 2020 (2c5a14f)
<0.6> 2020-09-24T20:48:12.560862+02:00 Isengard kernel - - - [ 0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.12.14-lp151.28.67-default root=UUID=0d457df1-b43d-4587-aa5a-6c919bcbedb8 showopts resume=/dev/disk/by-label/Swap splash=verbose
That would indicate a power failure, but the thing is on an UPS. I tested
flipping the mains, for 5 seconds, and it survived. I was in the house, I
would have noticed any power failure bigger than 0.1 second.
A thermal event would be logged, and the machine is not loaded, anyway.
Let's see if the journal has something:
cer@Isengard:~> journalctl --list-boots
- -9 6454520f76ef411aae4048bcad74bf29 Tue 2020-01-28 02:12:23 CET—Sun 2020-02-23 20:40:58 CET
- -8 690c025a20e74949aa3b14a9920bc965 Sun 2020-02-23 20:41:43 CET—Sun 2020-04-12 19:41:11 CEST
- -7 c931092ce6b742a0bcef0e0c789cc2d9 Sun 2020-04-12 19:41:55 CEST—Fri 2020-07-03 22:12:29 CEST
- -6 d0d9dcc6f41d4d95bfb1bb7017fd89b9 Fri 2020-07-03 22:14:26 CEST—Thu 2020-09-24 13:59:17 CEST
- -5 deddc3a843ba4ace9580e54614df5918 Thu 2020-09-24 20:46:37 CEST—Sat 2021-01-16 16:27:19 CET
- -4 0078032ae9f446389daef4b9b1546184 Sat 2021-01-16 16:28:09 CET—Sat 2021-01-16 20:55:05 CET
- -3 71982ddc703b49398aac561ae95a491f Sun 2021-01-17 10:12:08 CET—Sun 2021-01-17 21:01:44 CET
- -2 ff6a60796ff94a26a514a8bff51fd3e0 Sun 2021-01-17 21:02:19 CET—Sat 2021-01-23 21:45:03 CET
- -1 84c1f9d5c6c34fdbb83fe961e42bef1f Sat 2021-01-23 22:01:18 CET—Sat 2021-01-23 22:24:05 CET
0 ecd9e2c9fa9945e4ae57ef7e55938b36 Sat 2021-01-23 22:24:40 CET—Sat 2021-01-23 22:46:51 CET
cer@Isengard:~>
I need session 2, -2 Sun 2021-01-17 21:02:19 CET—Sat 2021-01-23 21:45:03 CET
cer@Isengard:~> journalctl --boot=2
- -- Logs begin at Tue 2020-01-28 02:12:23 CET, end at Sat 2021-01-23 22:48:07 CET. --
No - then session 3?
- -- Logs begin at Tue 2020-01-28 02:12:23 CET, end at Sat 2021-01-23 22:48:38 CET. --
No... wtf?
cer@Isengard:~> journalctl --boot=ff6a60796ff94a26a514a8bff51fd3e0
- -- Logs begin at Tue 2020-01-28 02:12:23 CET, end at Sat 2021-01-23 22:48:38 CET. --
That's the whole journal, not number 2.
Where is the journal number 2? Another bug in the journal?
Ok, let's find the boot point by grepping the whole journal, old style
Jan 23 21:16:57 Isengard dbus-daemon[1109]: [system] Successfully activated service 'org.opensuse.Snapper'
Jan 23 21:21:56 Isengard systemd[1]: Starting Cleanup of Temporary Directories...
Jan 23 21:21:56 Isengard systemd-tmpfiles[5561]: [/usr/lib/tmpfiles.d/net-snmp.conf:1] Line references path below legacy directory /var/run/, updating /var/run/net-snmp → /run/net-snmp; please update the tmpfiles.d/ drop-in file accordin>
Jan 23 21:21:56 Isengard systemd-tmpfiles[5561]: [/usr/lib/tmpfiles.d/radvd.conf:1] Line references path below legacy directory /var/run/, updating /var/run/radvd → /run/radvd; please update the tmpfiles.d/ drop-in file accordingly.
Jan 23 21:21:56 Isengard systemd-tmpfiles[5561]: [/usr/lib/tmpfiles.d/samba.conf:1] Line references path below legacy directory /var/run/, updating /var/run/samba → /run/samba; please update the tmpfiles.d/ drop-in file accordingly.
Jan 23 21:21:56 Isengard systemd-tmpfiles[5561]: [/usr/lib/tmpfiles.d/svnserve.conf:1] Line references path below legacy directory /var/run/, updating /var/run/svnserve → /run/svnserve; please update the tmpfiles.d/ drop-in file accordin>
Jan 23 21:21:56 Isengard systemd-tmpfiles[5561]: [/usr/lib/tmpfiles.d/tmp.conf:13] Duplicate line for path "/var/tmp", ignoring.
Jan 23 21:21:56 Isengard systemd-tmpfiles[5561]: [/usr/lib/tmpfiles.d/var.conf:21] Duplicate line for path "/var/lib", ignoring.
Jan 23 21:21:56 Isengard systemd-tmpfiles[5561]: [/usr/lib/tmpfiles.d/var.conf:23] Duplicate line for path "/var/spool", ignoring.
Jan 23 21:21:56 Isengard systemd[1]: Started Cleanup of Temporary Directories.
Jan 23 21:30:03 Isengard Sistema[5776]: Logging the current external IP= (omitted)
Jan 23 21:33:54 Isengard smartd[1103]: Device: /dev/sda [SAT], SMART Usage Attribute: 189 Airflow_Temperature_Cel changed from 41 to 46
Jan 23 21:33:54 Isengard smartd[1103]: Device: /dev/sda [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 41 to 46
Jan 23 21:33:54 Isengard smartd[1103]: Device: /dev/disk/by-id/wwn-0x5000c5009399305f [SAT], SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 66 to 65
Jan 23 21:33:54 Isengard smartd[1103]: Device: /dev/disk/by-id/wwn-0x5000c5009399305f [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 34 to 35
Jan 23 21:45:03 Isengard Sistema[6157]: Logging the current external IP= (omitted)
- -- Reboot --
Jan 23 22:01:18 Isengard kernel: microcode: microcode updated early to revision 0x411, date = 2019-04-23
Jan 23 22:01:18 Isengard kernel: Linux version 5.3.18-lp152.60-default (geeko@buildhost) (gcc version 7.5.0 (SUSE Linux)) #1 SMP Tue Jan 12 23:10:31 UTC 2021 (9898712)
Jan 23 22:01:18 Isengard kernel: Command line: BOOT_IMAGE=/boot/vmlinuz-5.3.18-lp152.60-default root=UUID=0d457df1-b43d-4587-aa5a-6c919bcbedb8 showopts splash=verbose splash=silent resume=/dev/disk/by-label/Swap mitigations=auto quiet
Jan 23 22:01:18 Isengard kernel: x86/fpu: x87 FPU will use FXSAVE
Jan 23 22:01:18 Isengard kernel: BIOS-provided physical RAM map:
Jan 23 22:01:18 Isengard kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000003efff] usable
Jan 23 22:01:18 Isengard kernel: BIOS-e820: [mem 0x000000000003f000-0x000000000003ffff] ACPI NVS
Same info, that is, no info about what caused the machine to reboot. Has been fully reliable since I bought it on 2016:
2016-11-27 23:38:32+01:00 - Booting the system now ================================================================================ Linux Isengard 4.4.27-2-default #1 SMP Thu Nov 3 14:59:54 UTC 2016 (5c21e7c) x86_64 x86_64 x86_64 GNU/Linux
2016-12-04 15:44:09+01:00 - Halting the system now =========================================== uptime: 15:44pm up 6 days 16:06, 0 users, load average: 0.66, 0.25, 0.14
But now it is running Leap 15.2 since 2021-01-17, that is, a week ago.
It rebooted perhaps just at 22:00 - cronjob? I don't have, yet, any log
entry on a Saturday at 22:00 with leap 15.2, the machine was upgraded from 15.1
to 15.2 just on past Saturday (with DVD).
On previous 15.1, this is what happens normally:
<1.5> 2021-01-09T21:30:03.324416+01:00 Isengard Sistema - - - Logging the current external IP= (omitted)
<1.5> 2021-01-09T21:45:05.372100+01:00 Isengard Sistema - - - Logging the current external IP= (omitted)
<3.6> 2021-01-09T21:48:37.584878+01:00 Isengard smartd 25710 - - Device: /dev/sda [SAT], SMART Usage Attribute: 189 Airflow_Temperature_Cel changed from 42 to 43
<3.6> 2021-01-09T21:48:37.586113+01:00 Isengard smartd 25710 - - Device: /dev/sda [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 42 to 43
<3.6> 2021-01-09T21:48:37.640372+01:00 Isengard smartd 25710 - - Device: /dev/disk/by-id/wwn-0x5000c5009399305f [SAT], SMART Usage Attribute: 190 Airflow_Temperature_Cel changed from 67 to 66
<3.6> 2021-01-09T21:48:37.641443+01:00 Isengard smartd 25710 - - Device: /dev/disk/by-id/wwn-0x5000c5009399305f [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 33 to 34
<3.6> 2021-01-09T22:00:03.382980+01:00 Isengard systemd 1 - - Started Timeline of Snapper Snapshots.
<3.6> 2021-01-09T22:00:03.514452+01:00 Isengard dbus-daemon 1186 - - [system] Activating service name='org.opensuse.Snapper' requested by ':1.16079' (uid=0 pid=23673 comm="/usr/lib/snapper/systemd-helper --timeline ") (using servicehelper)
<3.6> 2021-01-09T22:00:03.775706+01:00 Isengard dbus-daemon 1186 - - [system] Successfully activated service 'org.opensuse.Snapper'
<1.5> 2021-01-09T22:00:08.698222+01:00 Isengard Sistema - - - Logging the current external IP= (omitted)
<9.6> 2021-01-09T22:15:01.744156+01:00 Isengard run-crons.daily - - - Considering my daily cron jobs.
<9.6> 2021-01-09T22:15:01.885387+01:00 Isengard run-crons.daily - - - Adjusting permissions of Video directories
I'll have to keep watching and see...
- --
Cheers
Carlos E. R.
(from 15.2 x86_64 at Telcontar)
-----BEGIN PGP SIGNATURE-----
iHoEARECADoWIQQZEb51mJKK1KpcU/W1MxgcbY1H1QUCYAyj2hwccm9iaW4ubGlz
dGFzQHRlbGVmb25pY2EubmV0AAoJELUzGBxtjUfV5CYAoIQFFQZGRpCSsGwRl9bj
jWgcgHdvAJ0TCi2Cbp5P5RkyBNJLKTzsNojWVw==
=D6AM
-----END PGP SIGNATURE-----