[Risolto] Strano problema journalctl

Avvio e configurazione del server X, KDE, GNOME e tutti gli altri DE/WM
Rispondi
andy86
Newbie
Messaggi: 14
Iscritto il: 14 settembre 2017, 16:42
Architettura: x86_64 (64bit)

[Risolto] Strano problema journalctl

Messaggio da andy86 » 15 marzo 2019, 23:08

Stasera mi sono messo ha spulciare journalctl per cercare la causa di uno strano freeze di Xorg (si è piantato tutto tranne l'audio) e mi sono trovato la sorpresa che journalctl non salva più nessun log dal 5 febbraio... :shock:

In pratica mi trovo questo:

Codice: Seleziona tutto

feb 05 00:54:08 pc-andre systemd[1]: Shutting down.
feb 05 00:54:08 pc-andre kernel: printk: systemd-shutdow: 43 output lines suppressed due to ratelimiting
feb 05 00:54:08 pc-andre systemd-shutdown[1]: Syncing filesystems and block devices.
feb 05 00:54:08 pc-andre systemd-journald[286]: Journal stopped
-- Reboot --
mar 15 22:49:14 pc-andre kernel: microcode: microcode updated early to revision 0x25, date = 2018-04-02
mar 15 22:49:14 pc-andre kernel: Linux version 5.0.2-arch1-1-ARCH (builduser@heftig-15298) (gcc version 8.2.1 20181127 (GCC)) #1 SMP PREEMPT Thu Mar 14 18:47:49 UTC 2019
mar 15 22:49:14 pc-andre kernel: Command line: BOOT_IMAGE=/boot/vmlinuz-linux root=UUID=720f961f-bdd1-4adf-b11a-f7851f865af3 rw quiet
Prima di quel -- Reboot -- ci sono tutti i log dal 22 gennaio (ho provato a forzare un po' di pulizia con i vari comandi "vacuum" e sono riuscito ad eliminare solo fino a quella data, dopo niente da fare), dopo quel -- Reboot -- appare solo il log della sessione corrente, così come fa dmesg, con il risultato che se sono costretto a riavviare tutto brutalmente (non mi entrava neanche in TTY) non posso andare a beccare il log appena precedente al riavvio.

Non credo sia normale sta cosa... :?
Ultima modifica di andy86 il 17 marzo 2019, 0:03, modificato 1 volta in totale.

Avatar utente
Dusty
Little John
Messaggi: 911
Iscritto il: 12 agosto 2011, 13:15
Località: Firenze

Re: Strano problema journalctl

Messaggio da Dusty » 16 marzo 2019, 11:18

Le prime righe del journal corrente non bastano per fare una diagnosi.
Per cominciare serve una verifica che la struttura su disco sia integra:
journalctl --verify
Poi vedere se il servizio ha comunicato qualcosa di interessante:
systemctl status systemd-journald
Quindi una ricerca grossolana nel journal corrente:
journalctl -b | grep journal

andy86
Newbie
Messaggi: 14
Iscritto il: 14 settembre 2017, 16:42
Architettura: x86_64 (64bit)

Re: Strano problema journalctl

Messaggio da andy86 » 16 marzo 2019, 11:38

Grazie.

Ho postato solo quelle righe per far meglio capire il problema, ovvero che il journal è incompleto, non posso vedere le righe corrispondenti al boot precedente, sembra che non ci siano.

La verifica sembra OK:

Codice: Seleziona tutto

journalctl --verify
PASS: /run/log/journal/0ba36dede91941878138fe43d0ddffdf/system.journal                                                                         
PASS: /var/log/journal/0ba36dede91941878138fe43d0ddffdf/user-1000.journal                                                                      
PASS: /var/log/journal/0ba36dede91941878138fe43d0ddffdf/system.journal
Il servizio dice:

Codice: Seleziona tutto

mar 16 10:46:08 pc-andre systemd-journald[272]: Journal started
mar 16 10:46:08 pc-andre systemd-journald[272]: Runtime journal (/run/log/journal/0ba36dede91941878138fe43d0ddffdf) is 8.0M, max 194.5M, 186.5M free.
mar 16 10:46:08 pc-andre systemd-journald[272]: Runtime journal (/run/log/journal/0ba36dede91941878138fe43d0ddffdf) is 8.0M, max 194.5M, 186.5M free.
Warning: Journal has been rotated since unit was started. Log output is incomplete or unavailable.
:?

La ricerca:

Codice: Seleziona tutto

journalctl -b | grep journal
mar 16 10:46:08 pc-andre systemd-journald[272]: Journal started
mar 16 10:46:08 pc-andre systemd-journald[272]: Runtime journal (/run/log/journal/0ba36dede91941878138fe43d0ddffdf) is 8.0M, max 194.5M, 186.5M free.
mar 16 10:46:08 pc-andre audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
mar 16 10:46:08 pc-andre kernel: audit: type=1130 audit(1552729568.411:8): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
mar 16 10:46:08 pc-andre systemd-journald[272]: Runtime journal (/run/log/journal/0ba36dede91941878138fe43d0ddffdf) is 8.0M, max 194.5M, 186.5M free.
mar 16 10:46:08 pc-andre audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
mar 16 10:46:08 pc-andre kernel: audit: type=1130 audit(1552729568.431:9): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
                                               journal_mode:        QVariant(QString, "wal") 

Avatar utente
TheSaint
Robin Hood
Messaggi: 7951
Iscritto il: 8 settembre 2007, 7:43
Architettura: x86_64 (64bit)
Località: davanti al computer

Re: Strano problema journalctl

Messaggio da TheSaint » 16 marzo 2019, 14:19

Se vuoi il journal dell'avvio precedente devi mettere -b -1
F the saint 13 anni da questo forum ;)
Gli uomini consumano il pianeta, almeno due di queste cose si possono ridurre.

andy86
Newbie
Messaggi: 14
Iscritto il: 14 settembre 2017, 16:42
Architettura: x86_64 (64bit)

Re: Strano problema journalctl

Messaggio da andy86 » 16 marzo 2019, 15:15

Grazie, quel comando non lo conoscevo, ma la situazione non cambia: anche così mi restituisce il journaling fino al 5 febbraio. Data dopo la quale il mio sistema sembra aver smesso di salvare il journaling in modo permanente... :?

Avatar utente
Dusty
Little John
Messaggi: 911
Iscritto il: 12 agosto 2011, 13:15
Località: Firenze

Re: Strano problema journalctl

Messaggio da Dusty » 16 marzo 2019, 18:27

andy86 ha scritto:
16 marzo 2019, 11:38
Il servizio dice:

Codice: Seleziona tutto

mar 16 10:46:08 pc-andre systemd-journald[272]: Journal started
mar 16 10:46:08 pc-andre systemd-journald[272]: Runtime journal (/run/log/journal/0ba36dede91941878138fe43d0ddffdf) is 8.0M, max 194.5M, 186.5M free.
mar 16 10:46:08 pc-andre systemd-journald[272]: Runtime journal (/run/log/journal/0ba36dede91941878138fe43d0ddffdf) is 8.0M, max 194.5M, 186.5M free.
Warning: Journal has been rotated since unit was started. Log output is incomplete or unavailable.
Sembra che il tuo sistema sia configurato per mantenere il jornal in memoria.
Per confronto, io mio dice:

Codice: Seleziona tutto

mar 16 10:01:12 PRIMO systemd-journald[309]: Journal started
mar 16 10:01:12 PRIMO systemd-journald[309]: Runtime journal (/run/log/journal/60fa22e2899744e28754aafed4aba9b4) is 8.0M, max>
mar 16 10:01:13 PRIMO systemd-journald[309]: Time spent on flushing to /var is 373.689ms for 811 entries.
mar 16 10:01:13 PRIMO systemd-journald[309]: System journal (/var/log/journal/60fa22e2899744e28754aafed4aba9b4) is 152.0M, ma>
Warning: Journal has been rotated since unit was started. Log output is incomplete or unavailable.
Cioè, sia il tuo che il mio iniziano a registrare in memoria (/run/log/); ma poco dopo il mio commmuta su disco (/var/log/) mentre il tuo resta in memoria (/run/log).
Controlla i parametri di journald.
Li trovi in:
/etc/systemd/journald.conf
/etc/systemd/journald.conf.d/*.conf
/run/systemd/journald.conf.d/*.conf
/usr/lib/systemd/journald.conf.d/*.conf
In particolare l'opzione 'storage', che dovrebbe essere 'auto' o 'persistent'.
Forse hai installato un pacchetto che l'ha modificata.
Leggi anche
man journald.conf

andy86
Newbie
Messaggi: 14
Iscritto il: 14 settembre 2017, 16:42
Architettura: x86_64 (64bit)

Re: Strano problema journalctl

Messaggio da andy86 » 16 marzo 2019, 19:30

Ma porc... avevo modificato io quell'opzione seguendo delle indicazioni su un forum inglese, l'obiettivo era quello di ridurre le dimensioni del file di log sul disco perché era arrivato a oltre 6gb, effettivamente le ho proprio ridotte. :oops: :roll:

Ho messo persistent e riavviato il servizio, ora dovremmo esserci:

Codice: Seleziona tutto

mar 16 19:23:33 pc-andre systemd-journald[1093]: System journal (/var/log/journal/0ba36dede91941878138fe43d0ddffdf) is 104.0M, max 4.0G, 3.8G free.
registra ancora in /var/log.

Per essere sicuri, avevo modificato anche queste righe:
RuntimeMaxUse=64M
MaxFileSec=1month

Mi sa che invece di seguire una procedura per ridurre lo spazio su disco ho seguito una procedura per ridurre lo spazio su ram, mannaggia a me... :mrgreen:

Comunque grazie per il supporto. Mo se si freeza ancora vedo se logga il problema.

Edit:

Ok, ho spulciato il man e ho configurato il journal così:
Storage=auto
SystemMaxUse=1000M #massimo 1GB su disco
RuntimeMaxUse=64M #massimo 64M su ram (questa era consigliata da qualche parte, non ricordo dove)
MaxRetentionSec=1month #massimo 1 mese di log

RISOLTO

Avatar utente
TheSaint
Robin Hood
Messaggi: 7951
Iscritto il: 8 settembre 2007, 7:43
Architettura: x86_64 (64bit)
Località: davanti al computer

Re: [Risolto] Strano problema journalctl

Messaggio da TheSaint » 17 marzo 2019, 15:27

Io ho messo

Codice: Seleziona tutto

SystemMaxUse=30M
in /etc/systemd/journald.conf e mi pare che basti. In fondo quando si registra due o tre sessioni il resto è poco utile, per un comune utente.
F the saint 13 anni da questo forum ;)
Gli uomini consumano il pianeta, almeno due di queste cose si possono ridurre.

Rispondi