survey for "got inotify poll request in wrong process - disabling inotify" log message

[RolandK]

hello,

i stumbled across "got inotify poll request in wrong process - disabling inotify" several times in my logs, on different systems

according to

https://forum.proxmox.com/threads/m...-request-in-wrong-process.109099/#post-568251

"it should not happen" an would be "rather elusive".


it's an error message in syslog and it's unnecessarily catching an admin's attention, who needs to get along with all those real error messages and false positives.

i have searched this forum and there are refernces in >80 messages to this log entries.

i'd be happy if some volunteers who like this to get fixed could please check , if it also happens on your systems.


# journalctl -u pveproxy -u pvedaemon|wc -l

should return some number >0

please report together with pveversion ( regardless of you get 0 or >0).

maybe we can put some light onto this

thank you!

ps:
it's NOT my intention to make anybody nervous, i just want to help that we get rid of this

# cat /root/pvenodes.txt | while read host;do echo $host;ssh -n $host 'journalctl -u pveproxy -u pvedaemon |grep "disabling inotify"|wc -l;pveversion';done
pve1-bnkw
82
pve-manager/7.2-7/d0dd0e85 (running kernel: 5.15.53-1-pve)
pve2-bnkw
4
pve-manager/7.2-7/d0dd0e85 (running kernel: 5.15.39-3-pve)
pve1
158
pve-manager/7.3-3/c3928077 (running kernel: 5.15.74-1-pve)
pve2
23
pve-manager/7.3-3/c3928077 (running kernel: 5.15.74-1-pve)
pve3
3
pve-manager/7.3-3/c3928077 (running kernel: 5.15.74-1-pve)
pve4
4
pve-manager/7.3-3/c3928077 (running kernel: 5.15.74-1-pve)
pve5
2
pve-manager/7.3-3/c3928077 (running kernel: 5.15.74-1-pve)
pve6
2
pve-manager/7.3-3/c3928077 (running kernel: 5.15.74-1-pve)
pve-pc1
655
pve-manager/7.4-4/4a8501a8 (running kernel: 5.15.107-2-pve)
pve-pc2
108
pve-manager/7.4-4/4a8501a8 (running kernel: 5.15.107-2-pve)
pve-pc3
121
pve-manager/7.4-4/4a8501a8 (running kernel: 5.15.107-2-pve)
pve-pc4
87
pve-manager/7.4-4/4a8501a8 (running kernel: 5.15.107-2-pve)
pve-pc5
111
pve-manager/7.4-4/4a8501a8 (running kernel: 5.15.107-2-pve)

 

[leesteken]

I found only such message in the last week but from spiceproxy instead.

 

[urosss]

I get:
# pveversion
pve-manager/8.1.3/b46aac3b42da5d15 (running kernel: 6.5.11-7-pve)
# journalctl -u pveproxy -u pvedaemon|wc -l
31391

 

[urosss]

And:
# journalctl -u pveproxy -u pvedaemon |grep "disabling inotify"|wc -l;pveversion
332
pve-manager/8.1.3/b46aac3b42da5d15 (running kernel: 6.5.11-7-pve)

 

[codejp3]

I get this for pvecm:
pvecm[1377]: got inotify poll request in wrong process - disabling inotify

# journalctl -u pveproxy -u pvedaemon|wc -l
17378

# pveversion
pve-manager/8.1.10/4b06efb5db453f29 (running kernel: 6.5.13-3-pve)

 

[KrisFromFuture]

i have it too

must be worried about that?

 

[EvertM]

Are you still collecting?
I've limited the query to the last month with (

journalctl -u pveproxy -u pvedaemon --since "1 month ago" | grep 'disabling inotify' | wc -l

)

server 1:
count: 252
pveversion: pve-manager/8.3.2/3e76eec21c4a14a7 (running kernel: 6.8.12-6-pve)

server 2:
count: 1
pveversion: pve-manager/8.3.4/65224a0f9cd294a3 (running kernel: 6.8.12-8-pve)

(these 2 servers are clustered)

 

[urosss]

journalctl -u pveproxy -u pvedaemon --since "1 month ago" | grep 'disabling inotify' | wc -l

Server: 191 counts

 

[allaboutmike]

# journalctl -u pveproxy -u pvedaemon|wc -l
2491
# journalctl -u pveproxy -u pvedaemon |grep "disabling inotify"|wc -l;pveversion
11
pve-manager/8.3.5/dac3aa88bac3f300 (running kernel: 6.8.12-8-pve)

 

[EllerholdAG]

Host 1: 339
Host 2: 66
Host 3: 51

pveversion on all 3: pve-manager/8.3.5/dac3aa88bac3f300 (running kernel: 6.8.12-8-pve)

 

[gfngfn256]

Joining the survey:

~# journalctl -u pveproxy -u pvedaemon --since "1 month ago" | grep 'disabling inotify' | wc -l
3
~# journalctl -u pveproxy -u pvedaemon --since "2 month ago" | grep 'disabling inotify' | wc -l
4
~# journalctl -u pveproxy -u pvedaemon --since "3 month ago" | grep 'disabling inotify' | wc -l
8
~# journalctl -u pveproxy -u pvedaemon --since "4 month ago" | grep 'disabling inotify' | wc -l
17
~# journalctl -u pveproxy -u pvedaemon --since "5 month ago" | grep 'disabling inotify' | wc -l
24
~# journalctl -u pveproxy -u pvedaemon --since "6 month ago" | grep 'disabling inotify' | wc -l
27

 

[lnxme]

root@vproxm:~# journalctl -u pveproxy -u pvedaemon|wc -l
445446
root@vproxm:~# pveversion
pve-manager/8.3.5/dac3aa88bac3f300 (running kernel: 6.8.12-9-pve)
root@vproxm:~#

 

[gfngfn256]

root@vproxm:~# journalctl -u pveproxy -u pvedaemon|wc -l

Wrong command for "survey". You've searched the journal for ALL pveproxy & pvedaemon units (service).
You missed the grep-pipe part.

So command should have been:

journalctl -u pveproxy -u pvedaemon| grep 'disabling inotify' | wc -l

 

[lnxme]

Wrong command for "survey". You've searched the journal for ALL pveproxy & pvedaemon units (service).
You missed the grep-pipe part.

So command should have been:

journalctl -u pveproxy -u pvedaemon| grep 'disabling inotify' | wc -l

root@vproxm:~# journalctl -u pveproxy -u pvedaemon| grep 'disabling inotify' | wc -l
137

 

[discostu]

Freshly installed Proxmox PVE 9 last week. Upgraded to 9.0.5 right after installation. No special configuration, just plain ext4 with local-lvm. No ZFS, Ceph, Cluster or any other advanced features. Only a few linux VMs running.

Also got lots of them. Is that a bad thing? Seemingly everything runs fine so far.

root@proxmox1-online:~# journalctl | grep inotify
Aug 11 15:00:26 proxmox1-online pvecm[1528]: got inotify poll request in wrong process - disabling inotify
Aug 12 15:37:39 proxmox1-online pveproxy[42416]: got inotify poll request in wrong process - disabling inotify
Aug 14 10:04:39 proxmox1-online pveproxy[447873]: got inotify poll request in wrong process - disabling inotify
Aug 14 10:36:36 proxmox1-online pveproxy[453133]: got inotify poll request in wrong process - disabling inotify
Aug 14 11:13:09 proxmox1-online pveproxy[459986]: got inotify poll request in wrong process - disabling inotify
Aug 14 11:31:16 proxmox1-online pveproxy[463644]: got inotify poll request in wrong process - disabling inotify
Aug 14 12:16:32 proxmox1-online pveproxy[474216]: got inotify poll request in wrong process - disabling inotify
Aug 14 12:28:47 proxmox1-online pveproxy[4371]: got inotify poll request in wrong process - disabling inotify
Aug 14 13:48:34 proxmox1-online pveproxy[14315]: got inotify poll request in wrong process - disabling inotify
Aug 14 14:07:45 proxmox1-online pveproxy[17360]: got inotify poll request in wrong process - disabling inotify
Aug 15 00:02:26 proxmox1-online pveproxy[118806]: got inotify poll request in wrong process - disabling inotify
Aug 15 16:57:37 proxmox1-online pveproxy[293635]: got inotify poll request in wrong process - disabling inotify
Aug 15 17:09:41 proxmox1-online pveproxy[295816]: got inotify poll request in wrong process - disabling inotify
Aug 15 17:31:06 proxmox1-online pveproxy[299556]: got inotify poll request in wrong process - disabling inotify
Aug 15 17:52:45 proxmox1-online pveproxy[303337]: got inotify poll request in wrong process - disabling inotify
Aug 15 17:53:28 proxmox1-online pveproxy[303453]: got inotify poll request in wrong process - disabling inotify
Aug 15 18:20:56 proxmox1-online pveproxy[308570]: got inotify poll request in wrong process - disabling inotify
Aug 15 19:18:07 proxmox1-online pveproxy[318550]: got inotify poll request in wrong process - disabling inotify
Aug 15 19:58:08 proxmox1-online pveproxy[325416]: got inotify poll request in wrong process - disabling inotify
Aug 15 20:09:04 proxmox1-online pveproxy[327388]: got inotify poll request in wrong process - disabling inotify
Aug 15 22:12:54 proxmox1-online pveproxy[348644]: got inotify poll request in wrong process - disabling inotify
Aug 15 23:13:01 proxmox1-online pveproxy[358892]: got inotify poll request in wrong process - disabling inotify
Aug 15 23:50:36 proxmox1-online pveproxy[365316]: got inotify poll request in wrong process - disabling inotify
Aug 16 00:00:47 proxmox1-online pveproxy[367081]: got inotify poll request in wrong process - disabling inotify
Aug 16 00:25:44 proxmox1-online pveproxy[371363]: got inotify poll request in wrong process - disabling inotify
Aug 16 00:53:49 proxmox1-online pveproxy[376226]: got inotify poll request in wrong process - disabling inotify
Aug 16 01:45:13 proxmox1-online pveproxy[385030]: got inotify poll request in wrong process - disabling inotify
Aug 16 02:23:10 proxmox1-online pveproxy[391539]: got inotify poll request in wrong process - disabling inotify
Aug 16 02:56:38 proxmox1-online pveproxy[397251]: got inotify poll request in wrong process - disabling inotify
Aug 16 03:37:38 proxmox1-online pveproxy[404273]: got inotify poll request in wrong process - disabling inotify
Aug 16 04:14:56 proxmox1-online pveproxy[410648]: got inotify poll request in wrong process - disabling inotify
Aug 16 04:44:27 proxmox1-online pveproxy[415695]: got inotify poll request in wrong process - disabling inotify
Aug 16 05:10:29 proxmox1-online pveproxy[420136]: got inotify poll request in wrong process - disabling inotify
Aug 16 05:30:47 proxmox1-online pveproxy[423608]: got inotify poll request in wrong process - disabling inotify
Aug 16 05:47:53 proxmox1-online pveproxy[426546]: got inotify poll request in wrong process - disabling inotify
Aug 16 06:25:29 proxmox1-online pveproxy[433020]: got inotify poll request in wrong process - disabling inotify
Aug 16 07:02:15 proxmox1-online pveproxy[439317]: got inotify poll request in wrong process - disabling inotify
Aug 16 07:45:16 proxmox1-online pveproxy[446675]: got inotify poll request in wrong process - disabling inotify
Aug 16 08:48:45 proxmox1-online pveproxy[457522]: got inotify poll request in wrong process - disabling inotify
Aug 16 08:57:09 proxmox1-online pveproxy[458946]: got inotify poll request in wrong process - disabling inotify
Aug 16 10:03:00 proxmox1-online pveproxy[470258]: got inotify poll request in wrong process - disabling inotify
Aug 16 10:28:14 proxmox1-online pveproxy[474587]: got inotify poll request in wrong process - disabling inotify
Aug 16 11:29:23 proxmox1-online pveproxy[485043]: got inotify poll request in wrong process - disabling inotify
Aug 16 12:01:10 proxmox1-online pveproxy[490465]: got inotify poll request in wrong process - disabling inotify
Aug 16 13:15:51 proxmox1-online pveproxy[503230]: got inotify poll request in wrong process - disabling inotify
Aug 16 13:26:31 proxmox1-online pveproxy[505055]: got inotify poll request in wrong process - disabling inotify
Aug 16 14:06:23 proxmox1-online pveproxy[511853]: got inotify poll request in wrong process - disabling inotify
Aug 16 14:35:50 proxmox1-online pveproxy[516897]: got inotify poll request in wrong process - disabling inotify
Aug 16 15:14:28 proxmox1-online pveproxy[523496]: got inotify poll request in wrong process - disabling inotify
Aug 16 15:56:52 proxmox1-online pveproxy[530741]: got inotify poll request in wrong process - disabling inotify
Aug 16 16:50:25 proxmox1-online pveproxy[539884]: got inotify poll request in wrong process - disabling inotify
Aug 16 18:01:57 proxmox1-online pveproxy[552133]: got inotify poll request in wrong process - disabling inotify
Aug 16 18:34:48 proxmox1-online pveproxy[557753]: got inotify poll request in wrong process - disabling inotify
Aug 16 18:46:03 proxmox1-online pveproxy[559672]: got inotify poll request in wrong process - disabling inotify
Aug 16 19:26:49 proxmox1-online pveproxy[566652]: got inotify poll request in wrong process - disabling inotify
Aug 16 19:44:23 proxmox1-online pveproxy[569673]: got inotify poll request in wrong process - disabling inotify
Aug 16 20:15:22 proxmox1-online pveproxy[574942]: got inotify poll request in wrong process - disabling inotify
Aug 16 20:44:14 proxmox1-online pveproxy[579864]: got inotify poll request in wrong process - disabling inotify
Aug 16 20:55:10 proxmox1-online pveproxy[581748]: got inotify poll request in wrong process - disabling inotify
Aug 16 21:57:35 proxmox1-online pveproxy[592448]: got inotify poll request in wrong process - disabling inotify
Aug 16 22:13:18 proxmox1-online pveproxy[595125]: got inotify poll request in wrong process - disabling inotify
Aug 16 22:42:34 proxmox1-online pveproxy[600108]: got inotify poll request in wrong process - disabling inotify
Aug 16 23:21:05 proxmox1-online pveproxy[606714]: got inotify poll request in wrong process - disabling inotify
Aug 16 23:40:49 proxmox1-online pveproxy[610140]: got inotify poll request in wrong process - disabling inotify
Aug 16 23:55:59 proxmox1-online pveproxy[612744]: got inotify poll request in wrong process - disabling inotify
Aug 17 00:54:54 proxmox1-online pveproxy[623174]: got inotify poll request in wrong process - disabling inotify
Aug 17 01:57:28 proxmox1-online pveproxy[633863]: got inotify poll request in wrong process - disabling inotify
Aug 17 02:16:20 proxmox1-online pveproxy[637070]: got inotify poll request in wrong process - disabling inotify
Aug 17 04:09:36 proxmox1-online pveproxy[656459]: got inotify poll request in wrong process - disabling inotify
Aug 17 05:21:29 proxmox1-online pveproxy[668771]: got inotify poll request in wrong process - disabling inotify
Aug 17 06:29:16 proxmox1-online pveproxy[680421]: got inotify poll request in wrong process - disabling inotify
Aug 17 06:57:18 proxmox1-online pveproxy[685214]: got inotify poll request in wrong process - disabling inotify
Aug 17 07:04:31 proxmox1-online pveproxy[686443]: got inotify poll request in wrong process - disabling inotify
Aug 17 07:22:06 proxmox1-online pveproxy[689517]: got inotify poll request in wrong process - disabling inotify
Aug 17 08:17:50 proxmox1-online pveproxy[699033]: got inotify poll request in wrong process - disabling inotify
Aug 17 08:45:18 proxmox1-online pveproxy[703746]: got inotify poll request in wrong process - disabling inotify
Aug 17 09:26:21 proxmox1-online pveproxy[710766]: got inotify poll request in wrong process - disabling inotify
Aug 17 09:36:23 proxmox1-online pveproxy[712478]: got inotify poll request in wrong process - disabling inotify
Aug 17 10:13:17 proxmox1-online pveproxy[718807]: got inotify poll request in wrong process - disabling inotify
Aug 17 10:41:02 proxmox1-online pveproxy[723648]: got inotify poll request in wrong process - disabling inotify
Aug 17 11:17:41 proxmox1-online pveproxy[730027]: got inotify poll request in wrong process - disabling inotify
Aug 17 11:46:45 proxmox1-online pveproxy[735017]: got inotify poll request in wrong process - disabling inotify
Aug 17 12:09:37 proxmox1-online pveproxy[738927]: got inotify poll request in wrong process - disabling inotify
Aug 17 12:19:33 proxmox1-online pveproxy[740679]: got inotify poll request in wrong process - disabling inotify
Aug 17 13:37:58 proxmox1-online pveproxy[754163]: got inotify poll request in wrong process - disabling inotify
Aug 17 13:53:52 proxmox1-online pveproxy[756881]: got inotify poll request in wrong process - disabling inotify
Aug 17 16:33:32 proxmox1-online pveproxy[784311]: got inotify poll request in wrong process - disabling inotify
Aug 17 16:58:29 proxmox1-online pveproxy[788574]: got inotify poll request in wrong process - disabling inotify
Aug 17 17:47:25 proxmox1-online pveproxy[796956]: got inotify poll request in wrong process - disabling inotify
Aug 17 18:19:53 proxmox1-online pveproxy[802571]: got inotify poll request in wrong process - disabling inotify
Aug 17 19:17:13 proxmox1-online pveproxy[812407]: got inotify poll request in wrong process - disabling inotify
Aug 17 19:30:34 proxmox1-online pveproxy[814699]: got inotify poll request in wrong process - disabling inotify
Aug 17 20:37:38 proxmox1-online pveproxy[826184]: got inotify poll request in wrong process - disabling inotify
Aug 17 20:56:51 proxmox1-online pveproxy[829461]: got inotify poll request in wrong process - disabling inotify
Aug 17 21:59:23 proxmox1-online pveproxy[840186]: got inotify poll request in wrong process - disabling inotify
Aug 17 23:00:15 proxmox1-online pveproxy[850657]: got inotify poll request in wrong process - disabling inotify
Aug 17 23:16:40 proxmox1-online pveproxy[853454]: got inotify poll request in wrong process - disabling inotify
Aug 17 23:41:12 proxmox1-online pveproxy[857668]: got inotify poll request in wrong process - disabling inotify
Aug 18 00:08:48 proxmox1-online pveproxy[862455]: got inotify poll request in wrong process - disabling inotify
Aug 18 00:49:48 proxmox1-online pveproxy[869480]: got inotify poll request in wrong process - disabling inotify
Aug 18 01:32:51 proxmox1-online pveproxy[876891]: got inotify poll request in wrong process - disabling inotify
Aug 18 01:40:23 proxmox1-online pveproxy[878206]: got inotify poll request in wrong process - disabling inotify
Aug 18 02:39:59 proxmox1-online pveproxy[888411]: got inotify poll request in wrong process - disabling inotify
Aug 18 02:58:23 proxmox1-online pveproxy[891583]: got inotify poll request in wrong process - disabling inotify
Aug 18 03:22:44 proxmox1-online pveproxy[895734]: got inotify poll request in wrong process - disabling inotify
Aug 18 03:42:57 proxmox1-online pveproxy[899228]: got inotify poll request in wrong process - disabling inotify
Aug 18 04:18:38 proxmox1-online pveproxy[905359]: got inotify poll request in wrong process - disabling inotify
Aug 18 05:37:07 proxmox1-online pveproxy[918859]: got inotify poll request in wrong process - disabling inotify
Aug 18 06:14:02 proxmox1-online pveproxy[925161]: got inotify poll request in wrong process - disabling inotify
Aug 18 07:32:34 proxmox1-online pveproxy[938724]: got inotify poll request in wrong process - disabling inotify
Aug 18 07:55:43 proxmox1-online pveproxy[942671]: got inotify poll request in wrong process - disabling inotify