Bug 1047039 - rsyslog consumes 100% CPU [NEEDINFO]
Summary: rsyslog consumes 100% CPU
Keywords:
Status: CLOSED ERRATA
Alias: None
Product: Fedora
Classification: Fedora
Component: systemd
Version: 20
Hardware: All
OS: Linux
unspecified
high
Target Milestone: ---
Assignee: systemd-maint
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard: https://fedoraproject.org/wiki/Common...
: 1047643 (view as bug list)
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2013-12-28 16:34 UTC by Michal Minar
Modified: 2024-10-29 07:29 UTC (History)
25 users (show)

Fixed In Version: systemd-208-15.fc20
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2014-03-08 03:34:24 UTC
Type: Bug
Embargoed:
mailinglists35: needinfo? (systemd-maint)


Attachments (Terms of Use)
rsyslog debug output from command line run (8.61 MB, application/gzip)
2013-12-30 19:20 UTC, Dimitris
no flags Details
output of journalctl after rsyslog start (13.64 KB, text/x-log)
2014-01-02 06:03 UTC, Michal Minar
no flags Details
file list of directory (6.63 KB, text/plain)
2014-01-24 04:51 UTC, Nathanael Noblet
no flags Details
patch 1/2 (2.24 KB, patch)
2014-02-27 06:20 UTC, Zbigniew Jędrzejewski-Szmek
no flags Details | Diff
patch 2/2 (2.42 KB, patch)
2014-02-27 06:20 UTC, Zbigniew Jędrzejewski-Szmek
no flags Details | Diff
Trace (9.92 KB, text/plain)
2014-05-06 23:28 UTC, Slobodan Sredojevic
no flags Details


Links
System ID Private Priority Status Summary Last Updated
FreeDesktop.org 83221 0 None None None Never

Description Michal Minar 2013-12-28 16:34:13 UTC
Description of problem:
  rsyslog with default configuration consumes 100% CPU when started without
  any output in /var/log/* produced.

Version-Release number of selected component (if applicable):
  rsyslog-7.4.2-2.fc20.x86_64

How reproducible:
  ALWAYS

Steps to Reproduce:
1. sudo systemctl start syslog
2. watch cpu load

Actual results: 
  100% CPU load without any obvious reason
  
Expected results:
  peaceful, low rotating fun and smooth desktop

Additional info:
  This made me realize that I don't actually need it thanks to journal.

Comment 1 Tomas Heinrich 2013-12-30 10:53:47 UTC
(In reply to Michal Minar from comment #0)
> Description of problem:
>   rsyslog with default configuration consumes 100% CPU when started without
>   any output in /var/log/* produced.

How did this happen?
After a package upgrade? Fresh f20 install? Upgrade from f18/f19?

> Steps to Reproduce:
> 1. sudo systemctl start syslog
> 2. watch cpu load

Can you get a strace?
Perhaps even the output from a debug mode (/sbin/rsyslogd -dn).

Can you try do delete /var/lib/rsyslog/imjournal.state and restart the daemon to see whether this solves it?

Comment 2 Paul Howarth 2013-12-30 12:29:00 UTC
I'm seeing this on a fresh f20 install. I didn't see it straight away but I noticed it after doing "yum install @kde-desktop".

Attaching strace to the running rsyslogd just showed:
# strace -p 19541
Process 19541 attached
select(1, NULL, NULL, NULL, {86346, 498588}

Running in debug mode produced a couple of lines of:
Dec 30 12:22:00 zion rsyslogd: log message from journal doesn't have MESSAGE
Dec 30 12:22:01 zion rsyslogd: log message from journal doesn't have MESSAGE

but nothing else.

Removing /var/lib/rsyslog/imjournal.state and restarting the daemon resulted in a lot of journal messages appearing in /var/log/messages but then nothing else.

CPU continues to be used at 100%.

Comment 3 Dimitris 2013-12-30 19:19:43 UTC
Got the same bug here:  F19 to F20 otherwise uneventful fedup.

Attaching a brief debug mode output here.

systemctl stopped rsyslog.service, deleted /var/lib/imjournal.state, systemctl started rsyslog.service:  rsyslogd still pegged 1 core at 100%.

I only have the "base" rsyslog.x86_64 package installed.

Comment 4 Dimitris 2013-12-30 19:20:30 UTC
Created attachment 843494 [details]
rsyslog debug output from command line run

Comment 5 Tomas Heinrich 2014-01-01 19:04:03 UTC
Thank you both for the information. Looking at the code, this might even be a bug / change in journald.

Could you please try to delete (backup) /var/log/journal/* and see if that helped?

If that doesn't help, an interim workaround is to edit /etc/rsyslog.conf to use the imuxsock module instead of imjournal. I.e. commenting out

$ModLoad imjournal
$OmitLocalLogging on
$IMJournalStateFile imjournal.state

Comment 6 Tomas Heinrich 2014-01-01 19:15:24 UTC
*** Bug 1047643 has been marked as a duplicate of this bug. ***

Comment 7 Tomas Heinrich 2014-01-01 21:09:47 UTC
That should have been "delete /var/log/journal/* or /run/log/journal/*, whichever is in use".

Also, when rsyslog is in a busy-loop, what does journalctl report?

Comment 8 Michal Minar 2014-01-02 06:03:33 UTC
Created attachment 844370 [details]
output of journalctl after rsyslog start

Deleting /var/log/journal/* did indeed help. I haven't been able to reproduce the problem afterwards (restarting journal, rsylogd, rebooting). And unfortunately I didn't make a backup. 

Attached is the output of journalctl when rsylog got into busy-loop. SELinux is in permissive mode.

Comment 9 Paul Howarth 2014-01-05 16:26:25 UTC
Deleting /var/log/journal/* and rebooting worked for me too.

journalctl didn't show anything unusual when rsyslog started and was busy-looping prior to that, just the usual daemon start-up message.

Comment 10 Paulo Roma Cavalcanti 2014-01-08 15:45:15 UTC
I had the issue again, but this time I noticed that my / partition
was increased suddenly by almost 10GB. It turned out that /var/log/messages
and the files in /var/log/journal/ were too large.

I deleted the message file and the contents of /var/log/journal/,
but this time I also uninstalled rsyslog. It does not make much sense
having two log systems anyway. 

http://0pointer.de/blog/projects/journalctl.html

So far, so good.

Comment 11 Tomas Heinrich 2014-01-09 19:42:21 UTC
(In reply to Paulo Roma Cavalcanti from comment #10)
> I had the issue again, but this time I noticed that my / partition
> was increased suddenly by almost 10GB. It turned out that /var/log/messages
> and the files in /var/log/journal/ were too large.

If those locations grow in size considerably, it might suggest some process has run amok or there might be a bug in systemd/journal and rsyslog gets hit.

> It does not make much sense having two log systems anyway. 

journal can't do everything rsyslog does and vice versa.

The messages from comment#2 look malformed. Not sure if the MESSAGE field is mandatory in journal.

If somebody can still reproduce this behavior and is willing to test, can you compile and run the example from the page sd_journal_next(3) man page to see what's actually coming out of journal? Maybe journalctl has a way of limiting the output.

Switching to systemd; either there's a flood of malformed messages or the api needs to be used in a way I'm not aware of.

Comment 12 Alexander Wigen 2014-01-12 21:49:50 UTC
Not sure if this is the same bug?

Output from "top H":
  PID USER      PR  NI    VIRT    RES    SHR S %CPU %MEM     TIME+ COMMAND
  713 root      20   0  815920  17560  14908 R 99.9  0.1 539:38.57 in:imjournal

(gdb) info threads
  Id   Target Id         Frame 
  4    Thread 0x7fa07a8bc700 (LWP 713) "in:imjournal" 0x00007fa08a61f795 in generic_array_get (f=f@entry=0x7fa08c958cd0, first=3737832, i=74, 
    ret=ret@entry=0x7fa07a8bbb50, offset=offset@entry=0x7fa07a8bbb58) at ../src/journal/journal-file.c:1423
  3    Thread 0x7fa07a0bb700 (LWP 714) "in:imklog" 0x00007fa08bea687d in read () at ../sysdeps/unix/syscall-template.S:81
  2    Thread 0x7fa0798ba700 (LWP 715) "rs:main Q:Reg" pthread_cond_wait@@GLIBC_2.3.2 () at ../nptl/sysdeps/unix/sysv/linux/x86_64/pthread_cond_wait.S:185
* 1    Thread 0x7fa08c4b2780 (LWP 650) "rsyslogd" 0x00007fa08af8f7b3 in select () at ../sysdeps/unix/syscall-template.S:81
(gdb) thread 4
[Switching to thread 4 (Thread 0x7fa07a8bc700 (LWP 713))]
#0  0x00007fa08a61f795 in generic_array_get (f=f@entry=0x7fa08c958cd0, first=3737832, i=74, ret=ret@entry=0x7fa07a8bbb50, 
    offset=offset@entry=0x7fa07a8bbb58) at ../src/journal/journal-file.c:1423
1423            ci = hashmap_get(f->chain_cache, &first);
(gdb) bt
#0  0x00007fa08a61f795 in generic_array_get (f=f@entry=0x7fa08c958cd0, first=3737832, i=74, ret=ret@entry=0x7fa07a8bbb50, 
    offset=offset@entry=0x7fa07a8bbb58) at ../src/journal/journal-file.c:1423
#1  0x00007fa08a61fc7a in journal_file_next_entry (f=f@entry=0x7fa08c958cd0, o=<optimized out>, p=<optimized out>, 
    direction=direction@entry=DIRECTION_DOWN, ret=ret@entry=0x7fa07a8bbb50, offset=offset@entry=0x7fa07a8bbb58) at ../src/journal/journal-file.c:1991
#2  0x00007fa08a618f1b in next_with_matches (j=j@entry=0x7fa08c9583b0, f=f@entry=0x7fa08c958cd0, direction=direction@entry=DIRECTION_DOWN, 
    ret=ret@entry=0x7fa07a8bbb50, offset=offset@entry=0x7fa07a8bbb58) at ../src/journal/sd-journal.c:814
#3  0x00007fa08a61a76d in next_beyond_location (offset=<synthetic pointer>, ret=0x7fa07a8bbb40, direction=DIRECTION_DOWN, f=0x7fa08c958cd0, 
    j=0x7fa08c9583b0) at ../src/journal/sd-journal.c:873
#4  real_journal_next (j=0x7fa08c9583b0, direction=direction@entry=DIRECTION_DOWN) at ../src/journal/sd-journal.c:895
#5  0x00007fa08a61b3aa in sd_journal_next (j=<optimized out>) at ../src/journal/sd-journal.c:931
#6  0x00007fa08a8343a0 in runInput (pThrd=<optimized out>) at imjournal.c:533
#7  0x00007fa08c53eb00 in thrdStarter (arg=0x7fa08c966f50) at ../threads.c:212
#8  0x00007fa08be9ff33 in start_thread (arg=0x7fa07a8bc700) at pthread_create.c:309
#9  0x00007fa08af97ead in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:111

Comment 13 Tomas Heinrich 2014-01-12 22:18:23 UTC
Thanks for another another data point. It's likely the same bug.

I have a theory of what's happening but can't reproduce this myself. It would be great if somebody who hit this bug can try to run a minimalistic code that uses the journal api, such as the one from the sd_journal_next(3) man page and provide the output / describe the behavior.

Comment 14 Zbigniew Jędrzejewski-Szmek 2014-01-13 00:27:37 UTC
Can someone experiencing this attach (or post somewhere) the contents of /var/log/journal/ ?

Comment 15 Alexander Wigen 2014-01-14 23:34:52 UTC
Hi Zbigniew,

[alex@alex-ultrabook ~]$ ls -lh /var/log/journal/df93f6ef563440308214bf8c81ff44fe/
total 906M
-rw-r-----+ 1 root systemd-journal  23M Jul 11  2013 system~
-rw-r-----+ 1 root systemd-journal 105M Sep  2 20:37 system~
-rw-r-----+ 1 root systemd-journal  31M Sep 12 20:23 system~
-rw-r-----+ 1 root systemd-journal  85M Oct 31 09:09 system~
-rw-r-----+ 1 root systemd-journal  52M Nov 22 08:15 system~
-rw-r-----+ 1 root systemd-journal  78M Dec  4 18:46 system~
-rw-r-----+ 1 root systemd-journal  19M Dec  8 16:15 system~
-rw-r-----+ 1 root systemd-journal  56M Dec 23 22:51 system~
-rw-r-----+ 1 root systemd-journal  32M Dec 31 09:24 system~
-rw-r-----+ 1 root systemd-journal 8.0M Dec 31 09:34 system~
-rw-r-----+ 1 root systemd-journal 8.0M Dec 31 12:06 system~
-rw-r-----+ 1 root systemd-journal  16M Jan  1 12:59 system~
-rw-r-----+ 1 root systemd-journal  16M Jan  2 09:09 system~
-rw-r-----+ 1 root systemd-journal  40M Jan  9 10:31 system~
-rw-r-----+ 1 root systemd-journal  16M Jan 10 10:46 system~
-rw-r-----+ 1 root systemd-journal  88M Jul 11  2013 system
-rw-r-----+ 1 root systemd-journal 110M Aug  4 23:49 system
-rw-r-----+ 1 root systemd-journal  40M Jan 15 09:32 system.journal
-rw-r-----+ 1 root systemd-journal 4.4M Sep  2 20:38 user-1000~
-rw-r-----+ 1 root systemd-journal 5.5M Dec  8 16:16 user-1000~
-rw-r-----+ 1 root systemd-journal 8.0M Dec 23 22:51 user-1000~
-rw-r-----+ 1 root systemd-journal 8.0M Dec 31 09:35 user-1000~
-rw-r-----+ 1 root systemd-journal 8.0M Dec 31 12:06 user-1000~
-rw-r-----+ 1 root systemd-journal 8.0M Jan  1 13:02 user-1000~
-rw-r-----+ 1 root systemd-journal 8.0M Jan  9 10:54 user-1000~
-rw-r-----+ 1 root systemd-journal 8.0M Jan 10 10:46 user-1000~
-rw-r-----+ 1 root systemd-journal 4.9M Aug  4 23:49 user-1000
-rw-r-----+ 1 root systemd-journal 4.0M Jul 11  2013 user-1000
-rw-r-----+ 1 root systemd-journal 8.0M Jan 15 09:07 user-1000.journal
-rw-r-----+ 1 root systemd-journal 3.6M Dec  1 03:11 user-990.journal
-rw-r-----+ 1 root systemd-journal 8.0M Jan  9 10:30 user-999.journal

Comment 16 Zbigniew Jędrzejewski-Szmek 2014-01-14 23:57:46 UTC
By contents, I meant the actual contents :) If you xz them, they shouldn't be more than a hundred megs or so.

Comment 17 Alexander Wigen 2014-01-15 01:21:57 UTC
Hi Zbigniew,

I moved the df93f6ef563440308214bf8c81ff44fe folder to .old and restarted, the problem has now gone away. So the issue is related to the contents of these files. Do these files contain any sensitive information?

Comment 18 Zbigniew Jędrzejewski-Szmek 2014-01-15 01:51:50 UTC
(In reply to Alexander Wigen from comment #17)
> I moved the df93f6ef563440308214bf8c81ff44fe folder to .old and restarted,
> the problem has now gone away. So the issue is related to the contents of
> these files.
Yes, that is probable.

> Do these files contain any sensitive information?
You can review them with 'journalctl --directory ...'. Those are normal system logs, so the contain the usual stuff: information about your hostname, IP address, running packages, users logging in and out. If it is a personal machine, that's probably fine to post publicly, if it is a multi-user server, then probably not.

Comment 19 Nathanael Noblet 2014-01-24 04:51:36 UTC
Created attachment 854746 [details]
file list of directory

So I had the same issue - here's a file listing of the directory that I moved to .old, are any of those of more interest than others?

Comment 20 Zbigniew Jędrzejewski-Szmek 2014-01-25 17:51:15 UTC
(In reply to Nathanael Noblet from comment #19)
> Created attachment 854746 [details]
> file list of directory
This is not interesting. The contents of those files are.

> So I had the same issue - here's a file listing of the directory that I
> moved to .old, are any of those of more interest than others?
Yes, but we don't know which. If you want to, you can try to find a minimum subset which still exhibits the bug.

Comment 21 Nathanael Noblet 2014-01-31 21:22:41 UTC
Which file? Do you want all of them? Is there likely going to be sensitive information in the logs?

Comment 22 Zbigniew Jędrzejewski-Szmek 2014-02-23 20:57:27 UTC
(In reply to Nathanael Noblet from comment #21)
> Which file?
The directory /var/log/journal/.

> Do you want all of them?
Yes. It's probably only a subset which is triggers a bug, but it's not obvious 
which subset.

> Is there likely going to be sensitive
> information in the logs?
Information about user names, ip addresses, host name, services running, etc. YMMV.

I got a set of files from Edgar Hoch which trigger the bug, which should suffice, so for now, I don't need anything more.

Comment 23 Zbigniew Jędrzejewski-Szmek 2014-02-27 06:20:15 UTC
Created attachment 868335 [details]
patch 1/2

Comment 24 Zbigniew Jędrzejewski-Szmek 2014-02-27 06:20:51 UTC
Created attachment 868336 [details]
patch 2/2

Comment 25 Fedora Update System 2014-03-05 21:38:06 UTC
systemd-208-15.fc20 has been submitted as an update for Fedora 20.
https://admin.fedoraproject.org/updates/systemd-208-15.fc20

Comment 26 Fedora Update System 2014-03-07 06:27:34 UTC
Package systemd-208-15.fc20:
* should fix your issue,
* was pushed to the Fedora 20 testing repository,
* should be available at your local mirror within two days.
Update it with:
# su -c 'yum update --enablerepo=updates-testing systemd-208-15.fc20'
as soon as you are able to.
Please go to the following url:
https://admin.fedoraproject.org/updates/FEDORA-2014-3522/systemd-208-15.fc20
then log in and leave karma (feedback).

Comment 27 Fedora Update System 2014-03-08 03:34:24 UTC
systemd-208-15.fc20 has been pushed to the Fedora 20 stable repository.  If problems still persist, please make note of it in this bug report.

Comment 28 Slobodan Sredojevic 2014-05-05 20:47:50 UTC
I think something nasty is just happening now - and again. It started happenning in last few days (after applying updates) for the first time for me on my production f20 x86 as my system became slow and something, probably /var/log/messages have eaten all remaining hard drive space.

[root@localhost var]# ls -lhS /var/log/messages
-rw------- 1 root root 1.4G May  5 22:26 /var/log/messages

When cpu is very high noticed systemd-journal and rsyslogd are amongst top five processes.

Comment 29 Adam Williamson 2014-05-05 21:50:40 UTC
slobodan: it's the kind of generic symptom which could be caused by various underlying problems - rsyslog is basically a mirror of the systemd journal, and if rate limiting doesn't kick in, it's certainly possible it can get filled with messages. have you looked to see what's actually in the file taking up all that space?

Comment 30 Slobodan Sredojevic 2014-05-05 23:32:32 UTC
Hi Adam, it is always repeating the same "block" in /var/log/messages, the most probably related to wifi usb dongle and updated kernel package. It is:

Call Trace:
<c0a08d57>] dump_stack+0x41/0x52
[<c045b5ee>] warn_slowpath_common+0x7e/0xa0
[<c0834235>] ? usb_submit_urb+0x1c5/0x550
[<c0834235>] ? usb_submit_urb+0x1c5/0x550
[<c045b64e>] warn_slowpath_fmt+0x3e/0x60
[<c0834235>] usb_submit_urb+0x1c5/0x550
[<e0d50018>] hif_usb_send+0xb8/0x310 [ath9k_htc]
[<e0d4e059>] htc_issue_send.constprop.1+0x59/0x70 [ath9k_htc]
[<e0d4e42d>] htc_send_epid+0x1d/0x30 [ath9k_htc]
[<e0d509fc>] ath9k_wmi_cmd+0xdc/0x170 [ath9k_htc]
[<e0d55f83>] ath9k_regwrite+0x63/0x120 [ath9k_htc]
[<e0a50b61>] ath_hw_cycle_counters_update+0x81/0x110 [ath]
[<e103f5a5>] ath9k_hw_ani_monitor+0x25/0x1e0 [ath9k_hw]
[<e0d55117>] ath9k_htc_ani_work+0x157/0x180 [ath9k_htc]
[<c0473f75>] process_one_work+0x115/0x380
[<c0474a8e>] worker_thread+0xfe/0x330
[<c0474990>] ? manage_workers.isra.28+0x250/0x250
[<c047a45c>] kthread+0xac/0xc0
[<c0a16ff7>] ret_from_kernel_thread+0x1b/0x28
[<c047a3b0>] ? insert_kthread_work+0x30/0x30
---[ end trace 09ec989c9e292e48 ]---


Something that is very scaring is that /var/log/messages opening timestamp was "May 5 20:55:46" and closing was "May 5 21:46:38". So, in less that an hour log file grew from zero to 1.4GB! Probably it stoped when it have eaten all free space on /.

Comment 31 Adam Williamson 2014-05-06 00:59:27 UTC
slobodan: OK, so it sounds like there's an issue with rate limiting and also a kernel bug...if you could file both it'd be great. The kernel bug probably at http://bugzilla.kernel.org/ . for the other one...does the systemd journal also get huge, or are the messages properly limited there? (the rate limiting issue may ultimately be on the kernel side too, but I think they're still two separate bugs).

Comment 32 Slobodan Sredojevic 2014-05-06 12:21:26 UTC
Related to content and size of /var/log/systemd:

[root@localhost slobodan]# du -hs /var/log/journal/542c47fae5eb4d1d873a1b41ef83927e/
2.4G	/var/log/journal/542c47fae5eb4d1d873a1b41ef83927e/

There are about 300 files in there, each of 8MB in size and timestamps reveal all files were created/modified that hour.

I'll try to reproduce this again and open appropriate report(s).

Comment 33 Adam Williamson 2014-05-06 16:16:56 UTC
Thanks. Can you include more context for the kernel trace, particularly the actual WARNING: message printed out?

Comment 34 Slobodan Sredojevic 2014-05-06 23:23:18 UTC
Sure, please take a look at the wifidongleflood.txt. This behaviour is not happening with previous stable kernel.

kernel 3.13.10-200.fc20.i686+PAE from @updates - works ok
kernel 3.14.2-200.fc20.i686+PAE from @updates - flooding
kernel 3.15.0-0.rc4.git0.1.fc21.i686+PAE from @fedora-rawhide-kernel-nodebug - flooding

Maybe wrong was that I have noticed high cpu usage and came here - I am not sure this belongs to this bug report as you said already, so I'll wait for your comment and probably file a bug at kern bugzilla. Should I mention rate limiting there ?

Comment 35 Slobodan Sredojevic 2014-05-06 23:28:12 UTC
Created attachment 893030 [details]
Trace

This is flooding /var/log/messages and /var/log/journal

Comment 36 Dasharath Mane 2014-06-03 23:44:46 UTC
hi,

We are using RHEL 2.6.32-358.18.1.el6.x86_64 , & facing same issue, would you please confirm if same bug is present in RHEL

Comment 37 Adam Williamson 2014-06-04 00:24:55 UTC
dasharath: this specific issue traced back to systemd's journal, and RHEL 6 does not use that, so it seems unlikely. I recommend reporting your issue separately through the usual support channels.

Comment 38 Adam Williamson 2014-06-04 00:29:25 UTC
jwb, linville - can you folks take a look at Slobodan's message and advise what he should do? I'm guessing he'll want to file a kernel bug for the actual issue, beyond that, is it worth reporting the spamming to kernel and/or systemd? thanks!

Comment 39 Slobodan Sredojevic 2014-06-04 13:52:14 UTC
adam: I have already reported it at kernel bugzilla as yoz suggested - https://bugzilla.kernel.org/show_bug.cgi?id=76311 , I think linville is cc-ed there.

Comment 40 Ferry Huberts 2014-10-21 19:08:58 UTC
I just had this.
The cause was pulseaudio

This afternoon I had been fooling around with the system clock to do NTP related tests, so the clock in the syslog was jumping around.
A strong hint from my point of view.

Comment 41 Ferry Huberts 2014-10-21 19:12:50 UTC
(In reply to Ferry Huberts from comment #40)
> I just had this.
> The cause was pulseaudio
> 
> This afternoon I had been fooling around with the system clock to do NTP
> related tests, so the clock in the syslog was jumping around.
> A strong hint from my point of view.

too quick.

F20

pulseaudio.x86_64                                     5.0-7.fc20  
pulseaudio-equalizer.noarch                           2.7-12.fc20 
pulseaudio-gdm-hooks.x86_64                           5.0-7.fc20  
pulseaudio-libs.i686                                  5.0-7.fc20  
pulseaudio-libs.x86_64                                5.0-7.fc20  
pulseaudio-libs-glib2.x86_64                          5.0-7.fc20  
pulseaudio-module-bluetooth.x86_64                    5.0-7.fc20  
pulseaudio-module-gconf.x86_64                        5.0-7.fc20  
pulseaudio-module-x11.x86_64                          5.0-7.fc20  
pulseaudio-module-zeroconf.x86_64                     5.0-7.fc20  
pulseaudio-utils.x86_64                               5.0-7.fc20  
rsyslog.x86_64                                      7.4.8-2.fc20

Comment 42 Toni Willberg 2014-10-24 18:40:44 UTC
I just got this on F20 as well.
Oct 24 21:36:23 twillber rsyslogd-2177: imjournal: begin to drop messages due to rate-limiting

/var/log/messages isn't flooded due to rate-limiting, but rsyslogd is consuming 100% of CPU:
  PID USER      PR  NI    VIRT    RES    SHR S  %CPU %MEM     TIME+ COMMAND                                                                           
19901 root      20   0  832976  28324  27592 S 100.3  0.4   2:16.00 rsyslogd                                                                          

The root cause is mmc_blk kernel module; I unplugged a card while it was in use.

Comment 43 Mai Ling 2024-10-28 22:56:57 UTC
(In reply to Tomas Heinrich from comment #1)
> (In reply to Michal Minar from comment #0)
> > Description of problem:
> >   rsyslog with default configuration consumes 100% CPU when started without
> >   any output in /var/log/* produced.
> 
> How did this happen?
> After a package upgrade? Fresh f20 install? Upgrade from f18/f19?
> 
> > Steps to Reproduce:
> > 1. sudo systemctl start syslog
> > 2. watch cpu load
> 
> Can you get a strace?
> Perhaps even the output from a debug mode (/sbin/rsyslogd -dn).
> 
> Can you try do delete /var/lib/rsyslog/imjournal.state and restart the
> daemon to see whether this solves it?

hello from 2024 and thank you for this hint. OL9.4. worked.
$ rpm -qi rsyslog
Name        : rsyslog
Version     : 8.2102.0
Release     : 117.el9

Comment 44 Mai Ling 2024-10-28 23:01:45 UTC
things I did that triggered it (not sure if reproductible)

cat /etc/rsyslog.d/10-user-drop.conf
user.* ~

cat /etc/systemd/journald.conf.d/rsyslog.conf
[Journal]
ForwardToSyslog=no

(attempting to drop user generated messages)

eventually stopped rsyslog altogether since it was still causing 5-6 percent cpu load even after restarting journald
started it about 6 hours later -> 100% cpu
applied remedy
back to 0% cpu

Comment 45 Mai Ling 2024-10-28 23:04:28 UTC
errr, nope. spoke too soon. back to 100% cpu

Comment 46 Mai Ling 2024-10-28 23:30:22 UTC
(In reply to Tomas Heinrich from comment #5)
> Thank you both for the information. Looking at the code, this might even be
> a bug / change in journald.
> 
> Could you please try to delete (backup) /var/log/journal/* and see if that
> helped?
> 
> If that doesn't help, an interim workaround is to edit /etc/rsyslog.conf to
> use the imuxsock module instead of imjournal. I.e. commenting out
> 
> $ModLoad imjournal
> $OmitLocalLogging on
> $IMJournalStateFile imjournal.state

this one helped

(2024 syntax file)

module(load="imuxsock"    # provides support for local system logging (e.g. via logger command)
       SysSock.Use="on") # Turn off message reception via local log socket;
                          # local messages are retrieved through imjournal now.
#module(load="imjournal"            # provides access to the systemd journal
#       UsePid="system" # PID nummber is retrieved as the ID of the process the journal entry originates from
#       StateFile="imjournal.state") # File to store the position in the journal

changed SysSock to on
commented out the imjournal

also, before disabling imjournal:

$ sudo timeout 1 /sbin/rsyslogd -dn > rsys
Starting parse
Entering state 0
Reducing stack by rule 1 (line 135):
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Reading a token: Next token is token BEGINOBJ ()
Shifting token BEGINOBJ ()
Entering state 3
Reading a token: Next token is token NAME ()
Reducing stack by rule 18 (line 161):
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 3 29 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 3 29
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Reading a token: Next token is token ENDOBJ ()
Shifting token ENDOBJ ()
Entering state 60
Reducing stack by rule 8 (line 142):
   $1 = token BEGINOBJ ()
   $2 = nterm nvlst ()
   $3 = token ENDOBJ ()
-> $$ = nterm obj ()
Stack now 0 1
Entering state 25
Reducing stack by rule 2 (line 136):
   $1 = nterm conf ()
   $2 = nterm obj ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Reading a token: Next token is token BEGINOBJ ()
Shifting token BEGINOBJ ()
Entering state 3
Reading a token: Next token is token NAME ()
Reducing stack by rule 18 (line 161):
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 3 29 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 3 29
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Reading a token: Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 3 29 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 3 29
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Reading a token: Next token is token ENDOBJ ()
Shifting token ENDOBJ ()
Entering state 60
Reducing stack by rule 8 (line 142):
   $1 = token BEGINOBJ ()
   $2 = nterm nvlst ()
   $3 = token ENDOBJ ()
-> $$ = nterm obj ()
Stack now 0 1
Entering state 25
Reducing stack by rule 2 (line 136):
   $1 = nterm conf ()
   $2 = nterm obj ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Reading a token: Next token is token BEGINOBJ ()
Shifting token BEGINOBJ ()
Entering state 3
Reading a token: Next token is token NAME ()
Reducing stack by rule 18 (line 161):
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 3 29 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 3 29
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Reading a token: Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 3 29 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 3 29
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Reading a token: Next token is token ENDOBJ ()
Shifting token ENDOBJ ()
Entering state 60
Reducing stack by rule 8 (line 142):
   $1 = token BEGINOBJ ()
   $2 = nterm nvlst ()
   $3 = token ENDOBJ ()
-> $$ = nterm obj ()
Stack now 0 1
Entering state 25
Reducing stack by rule 2 (line 136):
   $1 = nterm conf ()
   $2 = nterm obj ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Reading a token: Next token is token BEGINOBJ ()
Shifting token BEGINOBJ ()
Entering state 3
Reading a token: Next token is token NAME ()
Reducing stack by rule 18 (line 161):
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 3 29 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 3 29
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Reading a token: Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 3 29 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 3 29
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Reading a token: Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 3 29 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 3 29
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 3
Entering state 29
Reading a token: Next token is token ENDOBJ ()
Shifting token ENDOBJ ()
Entering state 60
Reducing stack by rule 8 (line 142):
   $1 = token BEGINOBJ ()
   $2 = nterm nvlst ()
   $3 = token ENDOBJ ()
-> $$ = nterm obj ()
Stack now 0 1
Entering state 25
Reducing stack by rule 2 (line 136):
   $1 = nterm conf ()
   $2 = nterm obj ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Reading a token: Next token is token BEGIN_INCLUDE ()
Shifting token BEGIN_INCLUDE ()
Entering state 4
Reducing stack by rule 18 (line 161):
-> $$ = nterm nvlst ()
Stack now 0 1 4
Entering state 30
Reading a token: Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 4 30 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 4 30
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 4
Entering state 30
Reading a token: Next token is token NAME ()
Shifting token NAME ()
Entering state 59
Reading a token: Next token is token '=' ()
Shifting token '=' ()
Entering state 103
Reading a token: Next token is token STRING ()
Shifting token STRING ()
Entering state 139
Reducing stack by rule 21 (line 164):
   $1 = token STRING ()
-> $$ = nterm value ()
Stack now 0 1 4 30 59 103
Entering state 140
Reducing stack by rule 20 (line 163):
   $1 = token NAME ()
   $2 = token '=' ()
   $3 = nterm value ()
-> $$ = nterm nv ()
Stack now 0 1 4 30
Entering state 61
Reducing stack by rule 19 (line 162):
   $1 = nterm nvlst ()
   $2 = nterm nv ()
-> $$ = nterm nvlst ()
Stack now 0 1 4
Entering state 30
Reading a token: Next token is token ENDOBJ ()
Shifting token ENDOBJ ()
Entering state 62
Reducing stack by rule 7 (line 141):
   $1 = token BEGIN_INCLUDE ()
   $2 = nterm nvlst ()
   $3 = token ENDOBJ ()
-> $$ = nterm include ()
Stack now 0 1
Entering state 24
Reducing stack by rule 35 (line 186):
   $1 = nterm include ()
-> $$ = nterm stmt ()
Stack now 0 1
Entering state 26
Reducing stack by rule 3 (line 137):
   $1 = nterm conf ()
   $2 = nterm stmt ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Reading a token: Next token is token PRIFILT ()
Shifting token PRIFILT ()
Entering state 17
Reading a token: Next token is token LEGACY_ACTION ()
Shifting token LEGACY_ACTION ()
Entering state 15
Reducing stack by rule 42 (line 194):
   $1 = token LEGACY_ACTION ()
-> $$ = nterm s_act ()
Stack now 0 1 17
Entering state 28
Reducing stack by rule 39 (line 190):
   $1 = nterm s_act ()
-> $$ = nterm actlst ()
Stack now 0 1 17
Entering state 27
Reading a token: Next token is token PRIFILT ()
Reducing stack by rule 25 (line 168):
   $1 = nterm actlst ()
-> $$ = nterm stmt ()
Stack now 0 1 17
Entering state 51
Reducing stack by rule 37 (line 188):
   $1 = nterm stmt ()
-> $$ = nterm block ()
Stack now 0 1 17
Entering state 52
Reducing stack by rule 32 (line 183):
   $1 = token PRIFILT ()
   $2 = nterm block ()
-> $$ = nterm stmt ()
Stack now 0 1
Entering state 26
Reducing stack by rule 3 (line 137):
   $1 = nterm conf ()
   $2 = nterm stmt ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Next token is token PRIFILT ()
Shifting token PRIFILT ()
Entering state 17
Reading a token: Next token is token LEGACY_ACTION ()
Shifting token LEGACY_ACTION ()
Entering state 15
Reducing stack by rule 42 (line 194):
   $1 = token LEGACY_ACTION ()
-> $$ = nterm s_act ()
Stack now 0 1 17
Entering state 28
Reducing stack by rule 39 (line 190):
   $1 = nterm s_act ()
-> $$ = nterm actlst ()
Stack now 0 1 17
Entering state 27
Reading a token: Next token is token PRIFILT ()
Reducing stack by rule 25 (line 168):
   $1 = nterm actlst ()
-> $$ = nterm stmt ()
Stack now 0 1 17
Entering state 51
Reducing stack by rule 37 (line 188):
   $1 = nterm stmt ()
-> $$ = nterm block ()
Stack now 0 1 17
Entering state 52
Reducing stack by rule 32 (line 183):
   $1 = token PRIFILT ()
   $2 = nterm block ()
-> $$ = nterm stmt ()
Stack now 0 1
Entering state 26
Reducing stack by rule 3 (line 137):
   $1 = nterm conf ()
   $2 = nterm stmt ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Next token is token PRIFILT ()
Shifting token PRIFILT ()
Entering state 17
Reading a token: Next token is token LEGACY_ACTION ()
Shifting token LEGACY_ACTION ()
Entering state 15
Reducing stack by rule 42 (line 194):
   $1 = token LEGACY_ACTION ()
-> $$ = nterm s_act ()
Stack now 0 1 17
Entering state 28
Reducing stack by rule 39 (line 190):
   $1 = nterm s_act ()
-> $$ = nterm actlst ()
Stack now 0 1 17
Entering state 27
Reading a token: Next token is token PRIFILT ()
Reducing stack by rule 25 (line 168):
   $1 = nterm actlst ()
-> $$ = nterm stmt ()
Stack now 0 1 17
Entering state 51
Reducing stack by rule 37 (line 188):
   $1 = nterm stmt ()
-> $$ = nterm block ()
Stack now 0 1 17
Entering state 52
Reducing stack by rule 32 (line 183):
   $1 = token PRIFILT ()
   $2 = nterm block ()
-> $$ = nterm stmt ()
Stack now 0 1
Entering state 26
Reducing stack by rule 3 (line 137):
   $1 = nterm conf ()
   $2 = nterm stmt ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Next token is token PRIFILT ()
Shifting token PRIFILT ()
Entering state 17
Reading a token: Next token is token LEGACY_ACTION ()
Shifting token LEGACY_ACTION ()
Entering state 15
Reducing stack by rule 42 (line 194):
   $1 = token LEGACY_ACTION ()
-> $$ = nterm s_act ()
Stack now 0 1 17
Entering state 28
Reducing stack by rule 39 (line 190):
   $1 = nterm s_act ()
-> $$ = nterm actlst ()
Stack now 0 1 17
Entering state 27
Reading a token: Next token is token PRIFILT ()
Reducing stack by rule 25 (line 168):
   $1 = nterm actlst ()
-> $$ = nterm stmt ()
Stack now 0 1 17
Entering state 51
Reducing stack by rule 37 (line 188):
   $1 = nterm stmt ()
-> $$ = nterm block ()
Stack now 0 1 17
Entering state 52
Reducing stack by rule 32 (line 183):
   $1 = token PRIFILT ()
   $2 = nterm block ()
-> $$ = nterm stmt ()
Stack now 0 1
Entering state 26
Reducing stack by rule 3 (line 137):
   $1 = nterm conf ()
   $2 = nterm stmt ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Next token is token PRIFILT ()
Shifting token PRIFILT ()
Entering state 17
Reading a token: Next token is token LEGACY_ACTION ()
Shifting token LEGACY_ACTION ()
Entering state 15
Reducing stack by rule 42 (line 194):
   $1 = token LEGACY_ACTION ()
-> $$ = nterm s_act ()
Stack now 0 1 17
Entering state 28
Reducing stack by rule 39 (line 190):
   $1 = nterm s_act ()
-> $$ = nterm actlst ()
Stack now 0 1 17
Entering state 27
Reading a token: Next token is token PRIFILT ()
Reducing stack by rule 25 (line 168):
   $1 = nterm actlst ()
-> $$ = nterm stmt ()
Stack now 0 1 17
Entering state 51
Reducing stack by rule 37 (line 188):
   $1 = nterm stmt ()
-> $$ = nterm block ()
Stack now 0 1 17
Entering state 52
Reducing stack by rule 32 (line 183):
   $1 = token PRIFILT ()
   $2 = nterm block ()
-> $$ = nterm stmt ()
Stack now 0 1
Entering state 26
Reducing stack by rule 3 (line 137):
   $1 = nterm conf ()
   $2 = nterm stmt ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Next token is token PRIFILT ()
Shifting token PRIFILT ()
Entering state 17
Reading a token: Next token is token LEGACY_ACTION ()
Shifting token LEGACY_ACTION ()
Entering state 15
Reducing stack by rule 42 (line 194):
   $1 = token LEGACY_ACTION ()
-> $$ = nterm s_act ()
Stack now 0 1 17
Entering state 28
Reducing stack by rule 39 (line 190):
   $1 = nterm s_act ()
-> $$ = nterm actlst ()
Stack now 0 1 17
Entering state 27
Reading a token: Next token is token PRIFILT ()
Reducing stack by rule 25 (line 168):
   $1 = nterm actlst ()
-> $$ = nterm stmt ()
Stack now 0 1 17
Entering state 51
Reducing stack by rule 37 (line 188):
   $1 = nterm stmt ()
-> $$ = nterm block ()
Stack now 0 1 17
Entering state 52
Reducing stack by rule 32 (line 183):
   $1 = token PRIFILT ()
   $2 = nterm block ()
-> $$ = nterm stmt ()
Stack now 0 1
Entering state 26
Reducing stack by rule 3 (line 137):
   $1 = nterm conf ()
   $2 = nterm stmt ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Next token is token PRIFILT ()
Shifting token PRIFILT ()
Entering state 17
Reading a token: Next token is token LEGACY_ACTION ()
Shifting token LEGACY_ACTION ()
Entering state 15
Reducing stack by rule 42 (line 194):
   $1 = token LEGACY_ACTION ()
-> $$ = nterm s_act ()
Stack now 0 1 17
Entering state 28
Reducing stack by rule 39 (line 190):
   $1 = nterm s_act ()
-> $$ = nterm actlst ()
Stack now 0 1 17
Entering state 27
Reading a token: Next token is token PRIFILT ()
Reducing stack by rule 25 (line 168):
   $1 = nterm actlst ()
-> $$ = nterm stmt ()
Stack now 0 1 17
Entering state 51
Reducing stack by rule 37 (line 188):
   $1 = nterm stmt ()
-> $$ = nterm block ()
Stack now 0 1 17
Entering state 52
Reducing stack by rule 32 (line 183):
   $1 = token PRIFILT ()
   $2 = nterm block ()
-> $$ = nterm stmt ()
Stack now 0 1
Entering state 26
Reducing stack by rule 3 (line 137):
   $1 = nterm conf ()
   $2 = nterm stmt ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Next token is token PRIFILT ()
Shifting token PRIFILT ()
Entering state 17
Reading a token: Next token is token LEGACY_ACTION ()
Shifting token LEGACY_ACTION ()
Entering state 15
Reducing stack by rule 42 (line 194):
   $1 = token LEGACY_ACTION ()
-> $$ = nterm s_act ()
Stack now 0 1 17
Entering state 28
Reducing stack by rule 39 (line 190):
   $1 = nterm s_act ()
-> $$ = nterm actlst ()
Stack now 0 1 17
Entering state 27
Reading a token: Now at end of input.
Reducing stack by rule 25 (line 168):
   $1 = nterm actlst ()
-> $$ = nterm stmt ()
Stack now 0 1 17
Entering state 51
Reducing stack by rule 37 (line 188):
   $1 = nterm stmt ()
-> $$ = nterm block ()
Stack now 0 1 17
Entering state 52
Reducing stack by rule 32 (line 183):
   $1 = token PRIFILT ()
   $2 = nterm block ()
-> $$ = nterm stmt ()
Stack now 0 1
Entering state 26
Reducing stack by rule 3 (line 137):
   $1 = nterm conf ()
   $2 = nterm stmt ()
-> $$ = nterm conf ()
Stack now 0
Entering state 1
Now at end of input.
Shifting token $end ()
Entering state 2
Stack now 0 1 2
Cleanup: popping token $end ()
Cleanup: popping nterm conf ()
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslogd: imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]
rsyslog internal message (3,-2013): imjournal: fopen() failed for path: '/var/lib/rsyslog/imjournal.state.tmp': Too many open files [v8.2102.0-117.el9 try https://www.rsyslog.com/e/2013 ]


and in output I see events processed from seven days ago...

Comment 47 Mai Ling 2024-10-28 23:32:49 UTC
so apparently this bug or similar still occurs in EL9.4

Comment 48 Mai Ling 2024-10-28 23:37:42 UTC
looks like it panic'ed one week ago

$ journalctl -u rsyslog.service --no-hostname --no-pager | head -100
Oct 21 11:12:31 rsyslogd[768181]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:12:31 rsyslogd[768181]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:12:31 rsyslogd[768181]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:12:31 rsyslogd[768181]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:12:31 rsyslogd[768181]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:12:32 rsyslogd[768181]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:12:32 systemd-coredump[774022]: Process 768181 (rsyslogd) of user 0 dumped core.
Oct 21 11:12:32 systemd[1]: rsyslog.service: Main process exited, code=killed, status=7/BUS
Oct 21 11:12:32 systemd[1]: rsyslog.service: Failed with result 'signal'.
Oct 21 11:12:32 systemd[1]: rsyslog.service: Consumed 8min 48.184s CPU time.
Oct 21 11:12:32 systemd[1]: rsyslog.service: Scheduled restart job, restart counter is at 1043.
Oct 21 11:12:32 systemd[1]: Stopped System Logging Service.
Oct 21 11:12:32 systemd[1]: rsyslog.service: Consumed 8min 48.184s CPU time.
Oct 21 11:12:32 systemd[1]: Starting System Logging Service...
Oct 21 11:12:32 rsyslogd[774075]: [origin software="rsyslogd" swVersion="8.2102.0-117.el9" x-pid="774075" x-info="https://www.rsyslog.com"] start
Oct 21 11:12:32 systemd[1]: Started System Logging Service.
Oct 21 11:12:33 rsyslogd[774075]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:17:13 rsyslogd[774075]: imjournal from <podman1:web1prod>: begin to drop messages due to rate-limiting
Oct 21 11:22:33 rsyslogd[774075]: imjournal: 25601 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 11:27:30 rsyslogd[774075]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 11:32:37 rsyslogd[774075]: imjournal: 10424 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 11:41:33 rsyslogd[774075]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:41:33 rsyslogd[774075]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:41:34 rsyslogd[774075]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:41:34 rsyslogd[774075]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:41:34 rsyslogd[774075]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:41:34 systemd-coredump[775608]: Process 774075 (rsyslogd) of user 0 dumped core.
Oct 21 11:41:34 systemd[1]: rsyslog.service: Main process exited, code=killed, status=7/BUS
Oct 21 11:41:34 systemd[1]: rsyslog.service: Failed with result 'signal'.
Oct 21 11:41:34 systemd[1]: rsyslog.service: Consumed 2min 14.238s CPU time.
Oct 21 11:41:35 systemd[1]: rsyslog.service: Scheduled restart job, restart counter is at 1044.
Oct 21 11:41:35 systemd[1]: Stopped System Logging Service.
Oct 21 11:41:35 systemd[1]: rsyslog.service: Consumed 2min 14.238s CPU time.
Oct 21 11:41:35 systemd[1]: Starting System Logging Service...
Oct 21 11:41:35 rsyslogd[775662]: [origin software="rsyslogd" swVersion="8.2102.0-117.el9" x-pid="775662" x-info="https://www.rsyslog.com"] start
Oct 21 11:41:35 systemd[1]: Started System Logging Service.
Oct 21 11:41:35 rsyslogd[775662]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 11:59:36 rsyslogd[775662]: imjournal from <podman1:srv1tempdev>: begin to drop messages due to rate-limiting
Oct 21 12:01:40 rsyslogd[775662]: imjournal: 4184 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 12:09:22 rsyslogd[775662]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 12:11:41 rsyslogd[775662]: imjournal: 8042 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 12:20:10 rsyslogd[775662]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 12:20:30 rsyslogd[775662]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:20:31 rsyslogd[775662]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:20:31 rsyslogd[775662]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:20:31 rsyslogd[775662]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:20:31 rsyslogd[775662]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:20:32 systemd-coredump[777406]: Process 775662 (rsyslogd) of user 0 dumped core.
Oct 21 12:20:32 systemd[1]: rsyslog.service: Main process exited, code=killed, status=7/BUS
Oct 21 12:20:32 systemd[1]: rsyslog.service: Failed with result 'signal'.
Oct 21 12:20:32 systemd[1]: rsyslog.service: Consumed 2min 17.034s CPU time.
Oct 21 12:20:32 systemd[1]: rsyslog.service: Scheduled restart job, restart counter is at 1045.
Oct 21 12:20:32 systemd[1]: Stopped System Logging Service.
Oct 21 12:20:32 systemd[1]: rsyslog.service: Consumed 2min 17.034s CPU time.
Oct 21 12:20:32 systemd[1]: Starting System Logging Service...
Oct 21 12:20:32 rsyslogd[777462]: [origin software="rsyslogd" swVersion="8.2102.0-117.el9" x-pid="777462" x-info="https://www.rsyslog.com"] start
Oct 21 12:20:32 systemd[1]: Started System Logging Service.
Oct 21 12:20:32 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:28:27 rsyslogd[777462]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 12:30:33 rsyslogd[777462]: imjournal: 4068 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 12:37:13 rsyslogd[777462]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 12:40:36 rsyslogd[777462]: imjournal: 13624 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 12:44:41 rsyslogd[777462]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 12:50:37 rsyslogd[777462]: imjournal: 14552 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 12:50:37 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:50:37 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:50:38 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:50:38 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:50:38 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 12:56:47 rsyslogd[777462]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 13:00:39 rsyslogd[777462]: imjournal: 15289 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 13:07:23 rsyslogd[777462]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 13:10:43 rsyslogd[777462]: imjournal: 6529 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 13:18:29 rsyslogd[777462]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 13:20:44 rsyslogd[777462]: imjournal: 4499 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 13:22:26 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 13:22:26 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 13:22:26 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 13:22:26 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 13:22:26 rsyslogd[777462]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 13:22:27 systemd-coredump[780637]: Process 777462 (rsyslogd) of user 0 dumped core.
Oct 21 13:22:27 systemd[1]: rsyslog.service: Main process exited, code=killed, status=7/BUS
Oct 21 13:22:27 systemd[1]: rsyslog.service: Failed with result 'signal'.
Oct 21 13:22:27 systemd[1]: rsyslog.service: Consumed 4min 26.981s CPU time.
Oct 21 13:22:27 systemd[1]: rsyslog.service: Scheduled restart job, restart counter is at 1046.
Oct 21 13:22:27 systemd[1]: Stopped System Logging Service.
Oct 21 13:22:27 systemd[1]: rsyslog.service: Consumed 4min 26.981s CPU time.
Oct 21 13:22:27 systemd[1]: Starting System Logging Service...
Oct 21 13:22:27 rsyslogd[780690]: [origin software="rsyslogd" swVersion="8.2102.0-117.el9" x-pid="780690" x-info="https://www.rsyslog.com"] start
Oct 21 13:22:27 systemd[1]: Started System Logging Service.
Oct 21 13:22:27 rsyslogd[780690]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 13:28:48 rsyslogd[780690]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 13:32:28 rsyslogd[780690]: imjournal: 6434 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 13:41:04 rsyslogd[780690]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 13:42:31 rsyslogd[780690]: imjournal: 4488 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 13:48:05 rsyslogd[780690]: imjournal from <podman1:api1prod>: begin to drop messages due to rate-limiting
Oct 21 13:52:32 rsyslogd[780690]: imjournal: 9469 messages lost due to rate-limiting (20000 allowed within 600 seconds)
Oct 21 13:59:44 rsyslogd[780690]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 13:59:44 rsyslogd[780690]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]
Oct 21 13:59:44 rsyslogd[780690]: imjournal: journal files changed, reloading...  [v8.2102.0-117.el9 try https://www.rsyslog.com/e/0 ]


Note You need to log in before you can comment on or make changes to this bug.