Dotaz: journald - potlacenie zapisov na disk

[GandY]

Používam archlinux, minulý týždeň som menil notebook, disk som prehodil. Všetko ide ako má, až na to, že po naštartovaní KDE4 sa mi začne journal zapĺňať hláškami:

Jun 17 16:07:14 xxx kernel: WARNING: CPU: 3 PID: 464 at drivers/gpu/drm/i915/i915_drv.c:480 i915_semaphore_is_enabled+0x7c/0x90 [i915]()
Jun 17 16:07:14 xxx kernel: Modules linked in: ctr ccm iTCO_wdt iTCO_vendor_support gpio_ich snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic mousedev pci_stub v
Jun 17 16:07:14 xxx kernel:  i2c_designware_platform i2c_designware_core spi_pxa2xx_platform snd_hwdep drm snd_pcm snd_timer battery intel_gtt i2c_algo_bit e1000e snd acpi_pad
Jun 17 16:07:14 xxx kernel: CPU: 3 PID: 464 Comm: Xorg Tainted: G        W  O 3.14.44-1-lts #1
Jun 17 16:07:14 xxx kernel: Hardware name: Dell Inc. Latitude E5450/06PFH3, BIOS A05 01/05/2015
Jun 17 16:07:14 xxx kernel:  0000000000000000 0000000026047af4 ffff8800d585bbb0 ffffffff81502a83
Jun 17 16:07:14 xxx kernel:  0000000000000000 ffff8800d585bbe8 ffffffff8106a6dd ffff8800d6909b40
Jun 17 16:07:14 xxx kernel:  ffff8800d6908000 ffff880211ee5000 0000000000006830 ffff8800d5d9e900
Jun 17 16:07:14 xxx kernel: Call Trace:
Jun 17 16:07:14 xxx kernel:  [(ffffffff81502a83)] dump_stack+0x45/0x56
Jun 17 16:07:14 xxx kernel:  [(ffffffff8106a6dd)] warn_slowpath_common+0x7d/0xa0
Jun 17 16:07:14 xxx kernel:  [(ffffffff8106a80a)] warn_slowpath_null+0x1a/0x20
Jun 17 16:07:14 xxx kernel:  [(ffffffffa07efc0c)] i915_semaphore_is_enabled+0x7c/0x90 [i915]
Jun 17 16:07:14 xxx kernel:  [(ffffffffa0858248)] gen6_add_request+0x58/0x100 [i915]
Jun 17 16:07:14 xxx kernel:  [(ffffffffa0800ff2)] __i915_add_request+0x62/0x270 [i915]
Jun 17 16:07:14 xxx kernel:  [(ffffffffa080b206)] i915_gem_do_execbuffer.isra.11+0x7b6/0x1250 [i915]
Jun 17 16:07:14 xxx kernel:  [(ffffffffa080c17b)] i915_gem_execbuffer2+0x9b/0x290 [i915]
Jun 17 16:07:14 xxx kernel:  [(ffffffffa06a6ce2)] drm_ioctl+0x512/0x5f0 [drm]
Jun 17 16:07:14 xxx kernel:  [(ffffffffa080c0e0)] ? i915_gem_execbuffer+0x440/0x440 [i915]
Jun 17 16:07:14 xxx kernel:  [(ffffffff811c95d6)] do_vfs_ioctl+0x2a6/0x470
Jun 17 16:07:14 xxx kernel:  [(ffffffff813f88f9)] ? __sys_recvmsg+0x89/0xa0
Jun 17 16:07:14 xxx kernel:  [(ffffffff811c9821)] SyS_ioctl+0x81/0xa0
Jun 17 16:07:14 xxx kernel:  [(ffffffff81511289)] system_call_fastpath+0x16/0x1b
Jun 17 16:07:14 xxx kernel: ---[ end trace 97a4d234612d77f2 ]---
Jun 17 16:07:14 xxx kernel: ------------[ cut here ]------------

Za sekundu sa zobrazí stovky krat, čo znamená, že mi process journald žerie neustále 12-19% CPU:

$ journalctl | grep 'WARNING: CPU:' | grep -c '16:10:13'
338

Pokúsil som sa to obmedziť cez /etc/systemd/journald.conf a nastavil som:

RateLimitInterval=30s
RateLimitBurst=10

Podľa dokumentácie by to malo jeden typ správ obmedziť na 10 za pol minúty (ostatné by mal zahodiť a do logu zapísať údaj koľko ich bolo), ale nerobí to čo to má. Používam systemd vo verzii 219. Viete mi poradiť ako inak potlačiť dané správy.

Odpovědi

[Josef Kufner]

V první řadě aktualizuj ovladače grafiky, Xorg, jádro a knihovny obsahující v názvu mesa, glx, dri a podobné věci. Chce to jádro 4.0, Mesa 10.5, xserver-xorg-video-intel 2.99.něco

A pak si přečti man journald.conf a hned první dokumentovaná volba je Storage (zkus hodnotu volatile).

[GandY]

Prvé čo som spravil bolo hľadanie riešenia na internete, update systému a v neposlednej rade prečítanie man journald.conf. Tebou navrhnuté riešenie odbúra zápisy na disky, ale zaťaženie CPU ostáva. Dočasne som to vyriešil takto:

systemctl stop systemd-journald-dev-log.socket systemd-journald.socket systemd-journald

Čo ale nie je riešenie, na ktorom by som chcel dlhodobo ostať.

[Josef Kufner]

Zaťížení CPU vyřešíš odstraněním problému s grafikou.

[GandY]

Práveže zaťaženie robí journald! Daný warning ohľadom grafického ovládača mi nespôsobuje žiadne problémy.

[i915]

Souhlas s JK, chyba způsobuje více zápisů do logu a způsobuje více činnosti od logovacího démona, aspoň tak tomu rozumím já.

[GandY]

Ja nepopieram, že chyba (resp. warning správy) je v grafickom subsystéme, ale zatiaľ sa mi to nepodarilo vyriešiť. Keďže sa tento "problém" neprejavuje negatívne pri mojej práci, nemám dôvod to naliehavo riešiť. Jediný negatívny dopad bol práve iba v réžii journald. Rád by som daný typ správ potlačil spôsobom jednej správy za X sekúnd a za tým len informácia o počte koľko krát bola daná spáva za daný čas vygenerovaná (syslog to vedel). Prečítal som si dokumentáciu a nastavil som to v journald, ale nefunguje to.

[Peter Golis]

Ja som osobne obmedzoval journald na veľkosť, fungovalo to. A tiež som mal presmerovávanie do klasického syslogu, len v ňom som pre istotu zrušil konsolidáciu duplikátnych hlášok kôli fail2ban. Ale nebolo to na Arch Linuxe.

[GandY]

Obmedzenie na veľkosť používam, ale to nerieši tento problém. Otázka je či viem nastaviť journald iba ako "prietokový ohrievač", teda len aby posielal všetky správy do syslogu?

[michich]

Tomu nejbližší je nastavení Storage=none, ForwardToSyslog=yes a syslog nakonfigurovaný tak, aby četl ze socketu /run/systemd/journal/syslog.

[GandY]

Skúsil som, ale stále to nebolo ono :(. Po sčítaní záťaže procesov journald a syslog-ng som bol na tom horšie ako predtým. Pokiaľ sa nevyrieši problém s grafickým subsystémom ostávam bez logovania.