Jun 24 10:04:53 rpi4 vdr: [1168] VDR version 2.5.4 started Jun 24 10:04:53 rpi4 vdr: [1168] switched to user 'vdr' Jun 24 10:04:53 rpi4 vdr: [1168] codeset is 'UTF-8' - known Jun 24 10:04:53 rpi4 vdr: [1168] override character table is 'ISO-8859-9' - known Jun 24 10:04:54 rpi4 vdr: [1168] found 28 locales in /usr//share/locale Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'alb,sqi' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'bos' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'bul' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'chi,zho' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'eus,baq' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'iri,gle' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'jpn' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'lav' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'ltz' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'mlt' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'por' Jun 24 10:04:54 rpi4 vdr: [1168] no locale for language code 'smi' Jun 24 10:04:54 rpi4 vdr: [1168] loading plugin: /usr/lib/vdr/plugins/libvdr-remote.so.2.5.4 Jun 24 10:04:54 rpi4 vdr: [1168] loading plugin: /usr/lib/vdr/plugins/libvdr-satip.so.2.5.4 Jun 24 10:04:54 rpi4 vdr: [1168] loading plugin: /usr/lib/vdr/plugins/libvdr-softhddevice-drm.so.2.5.4 Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/setup.conf Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/sources.conf Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/diseqc.conf Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/scr.conf Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/channels.conf Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/timers.conf Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/svdrphosts.conf Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/remote.conf Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/keymacros.conf Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/camresponses.conf Jun 24 10:04:54 rpi4 vdr: [1169] video directory scanner thread started (pid=1168, tid=1169, prio=low) Jun 24 10:04:54 rpi4 vdr: [1170] epg data reader thread started (pid=1168, tid=1170, prio=high) Jun 24 10:04:54 rpi4 vdr: [1170] reading EPG data from /var/cache/vdr/epg.data Jun 24 10:04:54 rpi4 vdr: [1168] registered source parameters for 'A - ATSC' Jun 24 10:04:54 rpi4 vdr: [1168] registered source parameters for 'C - DVB-C' Jun 24 10:04:54 rpi4 vdr: [1168] registered source parameters for 'S - DVB-S' Jun 24 10:04:54 rpi4 vdr: [1168] registered source parameters for 'T - DVB-T' Jun 24 10:04:54 rpi4 vdr: [1168] no DVB device found Jun 24 10:04:54 rpi4 vdr: [1169] video directory scanner thread ended (pid=1168, tid=1169) Jun 24 10:04:54 rpi4 vdr: [1168] initializing plugin: remote (0.7.0): Fernbedienung Jun 24 10:04:54 rpi4 vdr: [1168] initializing plugin: satip (2.4.1-GIT-0a216f1): SAT>IP Geräte Jun 24 10:04:54 rpi4 vdr: [1171] SATIP poller thread started (pid=1168, tid=1171, prio=high) Jun 24 10:04:54 rpi4 vdr: [1168] cTimeMs: using monotonic clock (resolution is 1 ns) Jun 24 10:04:54 rpi4 vdr: [1168] SATIP: Adding server '192.168.200.101|DVBS2-2|gate' Bind: default Filters: none CI: no Quirks: none Jun 24 10:04:54 rpi4 vdr: [1168] new device number 1 (card index 1) Jun 24 10:04:54 rpi4 vdr: [1168] SATIP: Creating device CardIndex=0 DeviceNumber=0 [device 0] Jun 24 10:04:54 rpi4 vdr: [1172] SATIP#0 tuner thread started (pid=1168, tid=1172, prio=high) Jun 24 10:04:54 rpi4 vdr: [1173] SATIP#0 section handler thread started (pid=1168, tid=1173, prio=high) Jun 24 10:04:54 rpi4 vdr: [1174] device 1 section handler thread started (pid=1168, tid=1174, prio=low) Jun 24 10:04:54 rpi4 vdr: [1168] initializing plugin: softhddevice-drm (0.0.1rc1-GIT4cb719b): A software and GPU emulated HD device Jun 24 10:04:54 rpi4 vdr: [1168] new device number 2 (card index 2) Jun 24 10:04:54 rpi4 vdr: [1168] setting primary device to 1 Jun 24 10:04:54 rpi4 vdr: [1168] device 1 has no MPEG decoder Jun 24 10:04:54 rpi4 vdr: [1168] trying device number 2 instead Jun 24 10:04:54 rpi4 vdr: [1168] setting primary device to 2 Jun 24 10:04:54 rpi4 vdr: [1168] [softhddev]MakePrimaryDevice: 1 Jun 24 10:04:54 rpi4 vdr: audio/alsa: using device 'hw:CARD=Headphones' Jun 24 10:04:54 rpi4 vdr: audio/alsa: mixer default - PCM open Jun 24 10:04:54 rpi4 vdr: audio: wait on start condition Jun 24 10:04:54 rpi4 vdr: audio/alsa: flush state OPEN Jun 24 10:04:54 rpi4 vdr: FindDevice: Found Monitor Mode 1920x1080@50 Jun 24 10:04:54 rpi4 vdr: [1170] epg data reader thread ended (pid=1168, tid=1170) Jun 24 10:04:54 rpi4 vdr: [1168] [softhddev]SetVideoFormat: 1 Jun 24 10:04:54 rpi4 vdr: [1168] [softhddev]SetVideoDisplayFormat: 1 Jun 24 10:04:54 rpi4 vdr: [1168] [softhddev]GetSpuDecoder: Jun 24 10:04:54 rpi4 vdr: [1168] [softhddev]SetVolumeDevice: 135 Jun 24 10:04:54 rpi4 vdr: [1168] assuming manual start of VDR Jun 24 10:04:54 rpi4 vdr: [1168] setting current skin to "classic" Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/lib/vdr/themes/classic-default.theme Jun 24 10:04:54 rpi4 vdr: [1168] starting plugin: remote Jun 24 10:04:54 rpi4 vdr: [1168] remote: using 'tcp:3333' Jun 24 10:04:54 rpi4 vdr: [1168] starting plugin: satip Jun 24 10:04:54 rpi4 vdr: [1168] SATIP: Using CURL 7.64.0 rtsp Jun 24 10:04:54 rpi4 vdr: [1168] starting plugin: softhddevice-drm Jun 24 10:04:54 rpi4 vdr: [1168] remote control remote-tcp:3333 - keys known Jun 24 10:04:54 rpi4 vdr: [1168] remote control KBD - keys known Jun 24 10:04:54 rpi4 vdr: [1168] loading /var/cache/vdr/cam.data Jun 24 10:04:54 rpi4 vdr: [1168] switching to channel 1 S19.2E-1-1019-10301 (Das Erste HD) Jun 24 10:04:54 rpi4 vdr: [1177] KBD remote control thread started (pid=1168, tid=1177, prio=high) Jun 24 10:04:54 rpi4 vdr: [1178] device 1 receiver thread started (pid=1168, tid=1178, prio=high) Jun 24 10:04:54 rpi4 vdr: [1168] [softhddev]SetVolumeDevice: 135 Jun 24 10:04:54 rpi4 vdr: [1168] setting watchdog timer to 60 seconds Jun 24 10:04:54 rpi4 vdr: [1179] SVDRP server handler thread started (pid=1168, tid=1179, prio=low) Jun 24 10:04:54 rpi4 vdr: [1179] SVDRP rpi4 opening port 6419/tcp Jun 24 10:04:54 rpi4 vdr: [1179] SVDRP rpi4 listening on port 6419/tcp Jun 24 10:04:54 rpi4 vdr: [1168] [softhddev]SetPlayMode: 1 Jun 24 10:04:54 rpi4 vdr: video: display thread started Jun 24 10:04:54 rpi4 vdr: [1168] OSD size changed to 1920x1080 @ 1,77778 Jun 24 10:04:55 rpi4 vdr: [1171] SATIP: Detected 1 RTP packet error [device 0] Jun 24 10:04:55 rpi4 vdr: video: not detected Jun 24 10:04:55 rpi4 vdr: video: H264 detected Jun 24 10:04:55 rpi4 vdr: audio/demux: new channel id Jun 24 10:04:55 rpi4 vdr: CodecAudioOpen: Codec MP2 (MPEG audio layer 2) found Jun 24 10:04:55 rpi4 vdr: AlsaSetup: Channels 2 SampleRate 48000#012 HWChannels 2 HWSampleRate 48000 SampleFormat S16_LE#012 Supports pause: no mmap: yes#012 AlsaBufferTime 100ms AudioBufferTime 450ms Threshold 450ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 24ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 48ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 72ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 96ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 120ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 144ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 168ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 192ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 216ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 240ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 264ms to skip 0ms Jun 24 10:04:55 rpi4 vdr: [1168] max. latency time 1 seconds Jun 24 10:04:55 rpi4 vdr: AudioVideoReady: RB 264ms skip 264ms to skip 808ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 24ms to skip 808ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 24ms to skip 784ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 24ms to skip 760ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 24ms to skip 736ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 24ms to skip 712ms Jun 24 10:04:55 rpi4 vdr: audio: start? in Rb 24ms to skip 688ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 664ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 640ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 616ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 592ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 568ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 544ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 520ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 496ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 472ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 448ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 424ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 400ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 376ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 352ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 328ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 304ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 280ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 256ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 232ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 208ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 184ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 160ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 136ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 112ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 88ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 64ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 40ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 24ms to skip 16ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 32ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 56ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 80ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 104ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 128ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 152ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 176ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 200ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 224ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 248ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 272ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 296ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 320ms to skip 0ms Jun 24 10:04:56 rpi4 vdr: audio: start? in Rb 344ms to skip 0ms Jun 24 10:04:57 rpi4 vdr: audio: start? in Rb 368ms to skip 0ms Jun 24 10:04:57 rpi4 vdr: audio: start? in Rb 392ms to skip 0ms Jun 24 10:04:57 rpi4 vdr: audio: start? in Rb 416ms to skip 0ms Jun 24 10:04:57 rpi4 vdr: audio: start? in Rb 440ms to skip 0ms Jun 24 10:04:57 rpi4 vdr: audio: start? in Rb 464ms to skip 0ms Jun 24 10:04:57 rpi4 vdr: audio: ----> 464ms start Jun 24 10:04:57 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:04:57 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:04:57 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:04:57 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:04:57 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:00 rpi4 vdr: audio: wait on start condition Jun 24 10:05:00 rpi4 vdr: audio/alsa: flush state RUNNING Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 24ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 48ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 72ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 96ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 120ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 144ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 168ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 192ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 216ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 240ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 264ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 288ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 312ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 336ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 360ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 384ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 408ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 432ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 456ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 480ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 504ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 528ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 552ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 576ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 600ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 624ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 648ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 672ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 696ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 720ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 744ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 768ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 792ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 816ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 840ms to skip 0ms Jun 24 10:05:00 rpi4 vdr: audio: start? in Rb 864ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 888ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 912ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 936ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 960ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 984ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1008ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1032ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1056ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1080ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1104ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1128ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1152ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1176ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1200ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1224ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1248ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1272ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1296ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1320ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1344ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1368ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1392ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1416ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1440ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1464ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1488ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1512ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1536ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1560ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1584ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1608ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1632ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1656ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1680ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1704ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1728ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1752ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1776ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1800ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: start? in Rb 1824ms to skip 0ms Jun 24 10:05:01 rpi4 vdr: audio: ----> 1824ms start Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:02 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 kernel: [ 163.114315] ------------[ cut here ]------------ Jun 24 10:05:03 rpi4 kernel: [ 163.114352] WARNING: CPU: 1 PID: 35 at drivers/firmware/raspberrypi.c:64 rpi_firmware_transaction+0xec/0x128 Jun 24 10:05:03 rpi4 kernel: [ 163.114365] Firmware transaction timeout Jun 24 10:05:03 rpi4 kernel: [ 163.115145] CPU: 1 PID: 35 Comm: kworker/1:1 Tainted: G C 5.10.0-v7l+ #1380 Jun 24 10:05:03 rpi4 kernel: [ 163.115154] Hardware name: BCM2711 Jun 24 10:05:03 rpi4 kernel: [ 163.115171] Workqueue: events dbs_work_handler Jun 24 10:05:03 rpi4 kernel: [ 163.115186] Backtrace: Jun 24 10:05:03 rpi4 kernel: [ 163.115210] [] (dump_backtrace) from [] (show_stack+0x20/0x24) Jun 24 10:05:03 rpi4 kernel: [ 163.115224] r7:ffffffff r6:00000000 r5:60000013 r4:c12e693c Jun 24 10:05:03 rpi4 kernel: [ 163.115240] [] (show_stack) from [] (dump_stack+0xcc/0xf8) Jun 24 10:05:03 rpi4 kernel: [ 163.115257] [] (dump_stack) from [] (__warn+0xfc/0x114) Jun 24 10:05:03 rpi4 kernel: [ 163.115270] r10:d6c01008 r9:00000009 r8:c09977a0 r7:00000040 r6:00000009 r5:c09977a0 Jun 24 10:05:03 rpi4 kernel: [ 163.115279] r4:c0e98c7c r3:c1205094 Jun 24 10:05:03 rpi4 kernel: [ 163.115294] [] (__warn) from [] (warn_slowpath_fmt+0xa4/0xd8) Jun 24 10:05:03 rpi4 kernel: [ 163.115305] r7:00000040 r6:c0e98c7c r5:c1205048 r4:c0e98c9c Jun 24 10:05:03 rpi4 kernel: [ 163.115320] [] (warn_slowpath_fmt) from [] (rpi_firmware_transaction+0xec/0x128) Jun 24 10:05:03 rpi4 kernel: [ 163.115332] r9:c1a910c0 r8:00000018 r7:00000000 r6:ffffff92 r5:c1a910c0 r4:c1205048 Jun 24 10:05:03 rpi4 kernel: [ 163.115347] [] (rpi_firmware_transaction) from [] (rpi_firmware_property_list+0xbc/0x170) Jun 24 10:05:03 rpi4 kernel: [ 163.115358] r7:c1205048 r6:d6c01000 r5:00001000 r4:d6c01024 Jun 24 10:05:03 rpi4 kernel: [ 163.115372] [] (rpi_firmware_property_list) from [] (rpi_firmware_property+0x70/0x118) Jun 24 10:05:03 rpi4 kernel: [ 163.115384] r10:c58d564c r9:00030002 r8:00000018 r7:c1a910c0 r6:c1a7bd48 r5:0000000c Jun 24 10:05:03 rpi4 kernel: [ 163.115393] r4:c58d5640 Jun 24 10:05:03 rpi4 kernel: [ 163.115410] [] (rpi_firmware_property) from [] (raspberrypi_clock_property+0x54/0x7c) Jun 24 10:05:03 rpi4 kernel: [ 163.115422] r10:00000000 r9:00000000 r8:c22c8140 r7:00000000 r6:29b92700 r5:c1a7bd70 Jun 24 10:05:03 rpi4 kernel: [ 163.115431] r4:c1205048 r3:0000000c Jun 24 10:05:03 rpi4 kernel: [ 163.115447] [] (raspberrypi_clock_property) from [] (raspberrypi_fw_get_rate+0x4c/0x70) Jun 24 10:05:03 rpi4 kernel: [ 163.115456] r5:00000000 r4:c1205048 Jun 24 10:05:03 rpi4 kernel: [ 163.115471] [] (raspberrypi_fw_get_rate) from [] (clk_recalc+0x44/0x84) Jun 24 10:05:03 rpi4 kernel: [ 163.115479] r4:c20ce240 Jun 24 10:05:03 rpi4 kernel: [ 163.115491] [] (clk_recalc) from [] (__clk_recalc_rates+0x30/0x94) Jun 24 10:05:03 rpi4 kernel: [ 163.115500] r5:00000000 r4:c20ce240 Jun 24 10:05:03 rpi4 kernel: [ 163.115513] [] (__clk_recalc_rates) from [] (clk_core_get_rate_recalc+0x58/0x5c) Jun 24 10:05:03 rpi4 kernel: [ 163.115523] r7:00000000 r6:c1205048 r5:2faf0800 r4:c20ce240 Jun 24 10:05:03 rpi4 kernel: [ 163.115536] [] (clk_core_get_rate_recalc) from [] (clk_get_rate+0x28/0x38) Jun 24 10:05:03 rpi4 kernel: [ 163.115545] r5:2faf0800 r4:c22c8140 Jun 24 10:05:03 rpi4 kernel: [ 163.115559] [] (clk_get_rate) from [] (dev_pm_opp_set_rate+0x70/0x588) Jun 24 10:05:03 rpi4 kernel: [ 163.115569] r5:2faf0800 r4:c227da00 Jun 24 10:05:03 rpi4 kernel: [ 163.115584] [] (dev_pm_opp_set_rate) from [] (set_target+0x38/0x3c) Jun 24 10:05:03 rpi4 kernel: [ 163.115596] r10:00000000 r9:00000000 r8:00000002 r7:00000000 r6:c1403e44 r5:c1205048 Jun 24 10:05:03 rpi4 kernel: [ 163.115604] r4:c227dc00 Jun 24 10:05:03 rpi4 kernel: [ 163.115618] [] (set_target) from [] (__cpufreq_driver_target+0x1bc/0x540) Jun 24 10:05:03 rpi4 kernel: [ 163.115632] [] (__cpufreq_driver_target) from [] (od_dbs_update+0xc4/0x170) Jun 24 10:05:03 rpi4 kernel: [ 163.115644] r10:efee6600 r9:c402eb80 r8:c4022200 r7:c402e980 r6:c402eb80 r5:c402e980 Jun 24 10:05:03 rpi4 kernel: [ 163.115652] r4:c227dc00 Jun 24 10:05:03 rpi4 kernel: [ 163.115667] [] (od_dbs_update) from [] (dbs_work_handler+0x3c/0x64) Jun 24 10:05:03 rpi4 kernel: [ 163.115679] r9:00000000 r8:c131ad64 r7:c227dc00 r6:c402e984 r5:00000000 r4:c402e9b8 Jun 24 10:05:03 rpi4 kernel: [ 163.115695] [] (dbs_work_handler) from [] (process_one_work+0x254/0x584) Jun 24 10:05:03 rpi4 kernel: [ 163.115707] r9:00000000 r8:efee9900 r7:00000040 r6:efee6600 r5:c1a2d000 r4:c402e9b8 Jun 24 10:05:03 rpi4 kernel: [ 163.115722] [] (process_one_work) from [] (worker_thread+0x60/0x5c4) Jun 24 10:05:03 rpi4 kernel: [ 163.115734] r10:efee6600 r9:c1203d00 r8:efee6618 r7:00000008 r6:efee6600 r5:c1a2d014 Jun 24 10:05:03 rpi4 kernel: [ 163.115742] r4:c1a2d000 Jun 24 10:05:03 rpi4 kernel: [ 163.115757] [] (worker_thread) from [] (kthread+0x170/0x174) Jun 24 10:05:03 rpi4 kernel: [ 163.115769] r10:c1941e74 r9:c1a2d000 r8:c023dd94 r7:c1a7a000 r6:00000000 r5:c1a2c080 Jun 24 10:05:03 rpi4 kernel: [ 163.115777] r4:c1a2c0c0 Jun 24 10:05:03 rpi4 kernel: [ 163.115790] [] (kthread) from [] (ret_from_fork+0x14/0x28) Jun 24 10:05:03 rpi4 kernel: [ 163.115799] Exception stack(0xc1a7bfb0 to 0xc1a7bff8) Jun 24 10:05:03 rpi4 kernel: [ 163.115811] bfa0: 00000000 00000000 00000000 00000000 Jun 24 10:05:03 rpi4 kernel: [ 163.115822] bfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Jun 24 10:05:03 rpi4 kernel: [ 163.115832] bfe0: 00000000 00000000 00000000 00000000 00000013 00000000 Jun 24 10:05:03 rpi4 kernel: [ 163.115845] r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024570c Jun 24 10:05:03 rpi4 kernel: [ 163.115853] r4:c1a2c080 Jun 24 10:05:03 rpi4 kernel: [ 163.115864] ---[ end trace d1a18e6ca0cefa7d ]--- Jun 24 10:05:03 rpi4 kernel: [ 163.115895] cpu cpu0: dev_pm_opp_set_rate: failed to find current OPP for freq 4294967186 (-34) Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: [1178] [softhddev]Clear: Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:03 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: [1171] ERROR: 1 ring buffer overflow (376 bytes dropped) Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 kernel: [ 164.154381] hwmon hwmon1: Failed to get throttled (-110) Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:04 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 kernel: [ 165.194438] raspberrypi-clk soc:firmware:clocks: Failed to change fw-clk-arm frequency: -110 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:05 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 kernel: [ 166.251974] bcm2835_mmal_vchiq: timed out waiting for sync completion Jun 24 10:05:06 rpi4 kernel: [ 166.252002] bcm2835-codec bcm2835-codec: bcm2835_codec_stop_streaming: Failed disabling i/p port, ret -62 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:06 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:07 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:08 rpi4 kernel: [ 168.314430] bcm2835-codec bcm2835-codec: bcm2835_codec_stop_streaming: Timeout waiting for buffers to be returned - 4 outstanding Jun 24 10:05:08 rpi4 kernel: [ 168.314452] cpu cpu0: dev_pm_opp_set_rate: failed to find current OPP for freq 4294967186 (-34) Jun 24 10:05:08 rpi4 kernel: [ 168.314455] ------------[ cut here ]------------ Jun 24 10:05:08 rpi4 kernel: [ 168.314501] WARNING: CPU: 2 PID: 1178 at drivers/media/common/videobuf2/videobuf2-core.c:1945 __vb2_queue_cancel+0x1e0/0x258 [videobuf2_common] Jun 24 10:05:08 rpi4 kernel: [ 168.315279] CPU: 2 PID: 1178 Comm: device 1 receiv Tainted: G WC 5.10.0-v7l+ #1380 Jun 24 10:05:08 rpi4 kernel: [ 168.315288] Hardware name: BCM2711 Jun 24 10:05:08 rpi4 kernel: [ 168.315296] Backtrace: Jun 24 10:05:08 rpi4 kernel: [ 168.315325] [] (dump_backtrace) from [] (show_stack+0x20/0x24) Jun 24 10:05:08 rpi4 kernel: [ 168.315339] r7:ffffffff r6:00000000 r5:60000013 r4:c12e693c Jun 24 10:05:08 rpi4 kernel: [ 168.315356] [] (show_stack) from [] (dump_stack+0xcc/0xf8) Jun 24 10:05:08 rpi4 kernel: [ 168.315373] [] (dump_stack) from [] (__warn+0xfc/0x114) Jun 24 10:05:08 rpi4 kernel: [ 168.315386] r10:bf213738 r9:00000009 r8:bf2006b4 r7:00000799 r6:00000009 r5:bf2006b4 Jun 24 10:05:08 rpi4 kernel: [ 168.315395] r4:bf205f90 r3:c1205094 Jun 24 10:05:08 rpi4 kernel: [ 168.315410] [] (__warn) from [] (warn_slowpath_fmt+0x70/0xd8) Jun 24 10:05:08 rpi4 kernel: [ 168.315421] r7:00000799 r6:bf205f90 r5:c1205048 r4:00000000 Jun 24 10:05:08 rpi4 kernel: [ 168.315452] [] (warn_slowpath_fmt) from [] (__vb2_queue_cancel+0x1e0/0x258 [videobuf2_common]) Jun 24 10:05:08 rpi4 kernel: [ 168.315464] r9:bf15d098 r8:c3469be0 r7:00000001 r6:c36ca578 r5:c36ca578 r4:c36ca578 Jun 24 10:05:08 rpi4 kernel: [ 168.315509] [] (__vb2_queue_cancel [videobuf2_common]) from [] (vb2_core_streamoff+0x28/0xbc [videobuf2_common]) Jun 24 10:05:08 rpi4 kernel: [ 168.315521] r10:bf213738 r9:bf15d098 r8:c3469be0 r7:00000001 r6:0000000a r5:c36ca578 Jun 24 10:05:08 rpi4 kernel: [ 168.315530] r4:c36ca578 r3:0000000a Jun 24 10:05:08 rpi4 kernel: [ 168.315570] [] (vb2_core_streamoff [videobuf2_common]) from [] (vb2_streamoff+0x24/0x64 [videobuf2_v4l2]) Jun 24 10:05:08 rpi4 kernel: [ 168.315580] r5:c36ca578 r4:c36ca400 Jun 24 10:05:08 rpi4 kernel: [ 168.315616] [] (vb2_streamoff [videobuf2_v4l2]) from [] (v4l2_m2m_streamoff+0x40/0x10c [v4l2_mem2mem]) Jun 24 10:05:08 rpi4 kernel: [ 168.315654] [] (v4l2_m2m_streamoff [v4l2_mem2mem]) from [] (v4l2_m2m_ioctl_streamoff+0x20/0x24 [v4l2_mem2mem]) Jun 24 10:05:08 rpi4 kernel: [ 168.315665] r10:bf213738 r9:bf15d098 r8:c3469be0 r7:00000001 r6:c1205048 r5:40045613 Jun 24 10:05:08 rpi4 kernel: [ 168.315674] r4:bf68efd4 Jun 24 10:05:08 rpi4 kernel: [ 168.315764] [] (v4l2_m2m_ioctl_streamoff [v4l2_mem2mem]) from [] (v4l_streamoff+0x2c/0x30 [videodev]) Jun 24 10:05:08 rpi4 kernel: [ 168.315898] [] (v4l_streamoff [videodev]) from [] (__video_do_ioctl+0x234/0x460 [videodev]) Jun 24 10:05:08 rpi4 kernel: [ 168.315908] r5:40045613 r4:c34698a0 Jun 24 10:05:08 rpi4 kernel: [ 168.316036] [] (__video_do_ioctl [videodev]) from [] (video_usercopy+0x128/0x5ac [videodev]) Jun 24 10:05:08 rpi4 kernel: [ 168.316049] r10:c541de3c r9:a86fec3c r8:00000000 r7:c50a7b40 r6:c1205048 r5:40045613 Jun 24 10:05:08 rpi4 kernel: [ 168.316057] r4:40045613 Jun 24 10:05:08 rpi4 kernel: [ 168.316183] [] (video_usercopy [videodev]) from [] (video_ioctl2+0x20/0x24 [videodev]) Jun 24 10:05:08 rpi4 kernel: [ 168.316195] r10:0000000e r9:c541c000 r8:00000000 r7:c50a7b40 r6:c50a7b41 r5:c1205048 Jun 24 10:05:08 rpi4 kernel: [ 168.316203] r4:bf162fac Jun 24 10:05:08 rpi4 kernel: [ 168.316329] [] (video_ioctl2 [videodev]) from [] (v4l2_ioctl+0x4c/0x60 [videodev]) Jun 24 10:05:08 rpi4 kernel: [ 168.316403] [] (v4l2_ioctl [videodev]) from [] (sys_ioctl+0x1d4/0x8ec) Jun 24 10:05:08 rpi4 kernel: [ 168.316412] r5:c1205048 r4:40045613 Jun 24 10:05:08 rpi4 kernel: [ 168.316427] [] (sys_ioctl) from [] (ret_fast_syscall+0x0/0x28) Jun 24 10:05:08 rpi4 kernel: [ 168.316437] Exception stack(0xc541dfa8 to 0xc541dff0) Jun 24 10:05:08 rpi4 kernel: [ 168.316448] dfa0: b0402a48 40045613 0000000e 40045613 a86fec3c b0402940 Jun 24 10:05:08 rpi4 kernel: [ 168.316461] dfc0: b0402a48 40045613 b0400ab0 00000036 000000bc 00189000 00000000 00000000 Jun 24 10:05:08 rpi4 kernel: [ 168.316471] dfe0: b4cd928c a86fec24 b48db908 b6ae051c Jun 24 10:05:08 rpi4 kernel: [ 168.316483] r10:00000036 r9:c541c000 r8:c0200204 r7:00000036 r6:b0400ab0 r5:40045613 Jun 24 10:05:08 rpi4 kernel: [ 168.316492] r4:b0402a48 Jun 24 10:05:08 rpi4 kernel: [ 168.316503] ---[ end trace d1a18e6ca0cefa7e ]--- Jun 24 10:05:08 rpi4 kernel: [ 168.316519] videobuf2_common: driver bug: stop_streaming operation is leaving buf a9ac2081 in active state Jun 24 10:05:08 rpi4 kernel: [ 168.316539] videobuf2_common: driver bug: stop_streaming operation is leaving buf 15f5c8f2 in active state Jun 24 10:05:08 rpi4 kernel: [ 168.316557] videobuf2_common: driver bug: stop_streaming operation is leaving buf 05b3eed6 in active state Jun 24 10:05:08 rpi4 kernel: [ 168.316575] videobuf2_common: driver bug: stop_streaming operation is leaving buf ab4d2473 in active state Jun 24 10:05:08 rpi4 vdr: AudioGetClock: delay < 0 Jun 24 10:05:09 rpi4 kernel: [ 169.354446] raspberrypi-clk soc:firmware:clocks: Failed to change fw-clk-arm frequency: -110 Jun 24 10:05:10 rpi4 vdr: [1171] ERROR: 548 ring buffer overflows (720416 bytes dropped) Jun 24 10:05:16 rpi4 vdr: [1171] ERROR: 594 ring buffer overflows (780576 bytes dropped) Jun 24 10:05:22 rpi4 vdr: [1171] ERROR: 593 ring buffer overflows (779448 bytes dropped) Jun 24 10:05:28 rpi4 vdr: [1171] ERROR: 594 ring buffer overflows (780764 bytes dropped) Jun 24 10:05:34 rpi4 vdr: [1171] ERROR: 593 ring buffer overflows (780388 bytes dropped) Jun 24 10:05:40 rpi4 vdr: [1171] ERROR: 594 ring buffer overflows (781704 bytes dropped) Jun 24 10:05:46 rpi4 vdr: [1171] ERROR: 594 ring buffer overflows (780576 bytes dropped) Jun 24 10:06:06 rpi4 vdr: [1171] PANIC: watchdog timer expired - exiting! Jun 24 10:06:06 rpi4 vdr: [1177] KBD remote control thread ended (pid=1168, tid=1177) Jun 24 10:08:26 rpi4 kernel: [ 366.556568] INFO: task kworker/0:1:32 blocked for more than 122 seconds. Jun 24 10:08:26 rpi4 kernel: [ 366.556585] Tainted: G WC 5.10.0-v7l+ #1380 Jun 24 10:08:26 rpi4 kernel: [ 366.556599] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Jun 24 10:08:26 rpi4 kernel: [ 366.556614] task:kworker/0:1 state:D stack: 0 pid: 32 ppid: 2 flags:0x00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.556736] Workqueue: events output_poll_execute [drm_kms_helper] Jun 24 10:08:26 rpi4 kernel: [ 366.556761] Backtrace: Jun 24 10:08:26 rpi4 kernel: [ 366.556798] [] (__schedule) from [] (schedule+0x68/0xe4) Jun 24 10:08:26 rpi4 kernel: [ 366.556818] r10:00000002 r9:c1a75dcc r8:ffffe000 r7:c1a74000 r6:00000000 r5:c1a41f00 Jun 24 10:08:26 rpi4 kernel: [ 366.556832] r4:ffffe000 Jun 24 10:08:26 rpi4 kernel: [ 366.556852] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c) Jun 24 10:08:26 rpi4 kernel: [ 366.556867] r5:c1a75e5c r4:c1814104 Jun 24 10:08:26 rpi4 kernel: [ 366.556887] [] (schedule_preempt_disabled) from [] (__ww_mutex_lock.constprop.7+0x478/0x860) Jun 24 10:08:26 rpi4 kernel: [ 366.556907] [] (__ww_mutex_lock.constprop.7) from [] (__ww_mutex_lock_slowpath+0x20/0x24) Jun 24 10:08:26 rpi4 kernel: [ 366.556925] r10:00000001 r9:c1814af8 r8:c1814000 r7:00000000 r6:c1814104 r5:c1a75e5c Jun 24 10:08:26 rpi4 kernel: [ 366.556939] r4:c1814104 Jun 24 10:08:26 rpi4 kernel: [ 366.556958] [] (__ww_mutex_lock_slowpath) from [] (ww_mutex_lock+0x64/0x70) Jun 24 10:08:26 rpi4 kernel: [ 366.557140] [] (ww_mutex_lock) from [] (drm_modeset_lock+0x50/0x118 [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.557155] r5:00000000 r4:c1a75e5c Jun 24 10:08:26 rpi4 kernel: [ 366.557403] [] (drm_modeset_lock [drm]) from [] (drm_helper_probe_detect_ctx+0x74/0x138 [drm_kms_helper]) Jun 24 10:08:26 rpi4 kernel: [ 366.557419] r7:00000000 r6:c1205048 r5:c1814af8 r4:bf3a54a0 Jun 24 10:08:26 rpi4 kernel: [ 366.557578] [] (drm_helper_probe_detect_ctx [drm_kms_helper]) from [] (output_poll_execute+0x120/0x1d8 [drm_kms_helper]) Jun 24 10:08:26 rpi4 kernel: [ 366.557594] r7:00000000 r6:00000001 r5:c181420c r4:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.557683] [] (output_poll_execute [drm_kms_helper]) from [] (process_one_work+0x254/0x584) Jun 24 10:08:26 rpi4 kernel: [ 366.557701] r10:efed2600 r9:00000000 r8:efed5900 r7:00000000 r6:efed2600 r5:c197f600 Jun 24 10:08:26 rpi4 kernel: [ 366.557715] r4:c181420c Jun 24 10:08:26 rpi4 kernel: [ 366.557735] [] (process_one_work) from [] (worker_thread+0x60/0x5c4) Jun 24 10:08:26 rpi4 kernel: [ 366.557752] r10:efed2600 r9:c1203d00 r8:efed2618 r7:00000008 r6:efed2600 r5:c197f614 Jun 24 10:08:26 rpi4 kernel: [ 366.557765] r4:c197f600 Jun 24 10:08:26 rpi4 kernel: [ 366.557786] [] (worker_thread) from [] (kthread+0x170/0x174) Jun 24 10:08:26 rpi4 kernel: [ 366.557803] r10:c1915e74 r9:c197f600 r8:c023dd94 r7:c1a74000 r6:00000000 r5:c1a22740 Jun 24 10:08:26 rpi4 kernel: [ 366.557816] r4:c1a3b080 Jun 24 10:08:26 rpi4 kernel: [ 366.557835] [] (kthread) from [] (ret_from_fork+0x14/0x28) Jun 24 10:08:26 rpi4 kernel: [ 366.557849] Exception stack(0xc1a75fb0 to 0xc1a75ff8) Jun 24 10:08:26 rpi4 kernel: [ 366.557865] 5fa0: 00000000 00000000 00000000 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.557882] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.557898] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.557915] r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024570c Jun 24 10:08:26 rpi4 kernel: [ 366.557929] r4:c1a22740 Jun 24 10:08:26 rpi4 kernel: [ 366.557945] INFO: task kworker/3:1:33 blocked for more than 122 seconds. Jun 24 10:08:26 rpi4 kernel: [ 366.557959] Tainted: G WC 5.10.0-v7l+ #1380 Jun 24 10:08:26 rpi4 kernel: [ 366.557971] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Jun 24 10:08:26 rpi4 kernel: [ 366.557984] task:kworker/3:1 state:D stack: 0 pid: 33 ppid: 2 flags:0x00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.558023] Workqueue: events get_values_poll [raspberrypi_hwmon] Jun 24 10:08:26 rpi4 kernel: [ 366.558046] Backtrace: Jun 24 10:08:26 rpi4 kernel: [ 366.558076] [] (__schedule) from [] (schedule+0x68/0xe4) Jun 24 10:08:26 rpi4 kernel: [ 366.558093] r10:d6c02008 r9:eff07500 r8:eff07500 r7:c1205048 r6:c1a77d8c r5:c1a42e80 Jun 24 10:08:26 rpi4 kernel: [ 366.558107] r4:ffffe000 Jun 24 10:08:26 rpi4 kernel: [ 366.558126] [] (schedule) from [] (schedule_timeout+0x1d0/0x36c) Jun 24 10:08:26 rpi4 kernel: [ 366.558140] r5:c1203d00 r4:00054b38 Jun 24 10:08:26 rpi4 kernel: [ 366.558160] [] (schedule_timeout) from [] (wait_for_completion_timeout+0xb8/0x144) Jun 24 10:08:26 rpi4 kernel: [ 366.558177] r9:00000002 r8:ffffe000 r7:c197ef50 r6:c1205048 r5:00057e40 r4:c197ef4c Jun 24 10:08:26 rpi4 kernel: [ 366.558197] [] (wait_for_completion_timeout) from [] (mbox_send_message+0xb8/0x11c) Jun 24 10:08:26 rpi4 kernel: [ 366.558214] r9:c1a910c0 r8:00000010 r7:c1a77e28 r6:c197efb8 r5:0000000a r4:c197ef40 Jun 24 10:08:26 rpi4 kernel: [ 366.558234] [] (mbox_send_message) from [] (rpi_firmware_transaction+0x64/0x128) Jun 24 10:08:26 rpi4 kernel: [ 366.558250] r7:00000000 r6:d6c02000 r5:c1a910c0 r4:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.558269] [] (rpi_firmware_transaction) from [] (rpi_firmware_property_list+0xbc/0x170) Jun 24 10:08:26 rpi4 kernel: [ 366.558285] r7:c1205048 r6:d6c02000 r5:00001000 r4:d6c0201c Jun 24 10:08:26 rpi4 kernel: [ 366.558304] [] (rpi_firmware_property_list) from [] (rpi_firmware_property+0x70/0x118) Jun 24 10:08:26 rpi4 kernel: [ 366.558321] r10:c50270cc r9:00030046 r8:00000010 r7:c1a910c0 r6:c1a77ed8 r5:00000004 Jun 24 10:08:26 rpi4 kernel: [ 366.558335] r4:c50270c0 Jun 24 10:08:26 rpi4 kernel: [ 366.558356] [] (rpi_firmware_property) from [] (get_values_poll+0x4c/0x150 [raspberrypi_hwmon]) Jun 24 10:08:26 rpi4 kernel: [ 366.558373] r10:eff0e600 r9:00000000 r8:eff11900 r7:000000c0 r6:eff0e600 r5:c315b9cc Jun 24 10:08:26 rpi4 kernel: [ 366.558387] r4:c1205048 r3:00000004 Jun 24 10:08:26 rpi4 kernel: [ 366.558409] [] (get_values_poll [raspberrypi_hwmon]) from [] (process_one_work+0x254/0x584) Jun 24 10:08:26 rpi4 kernel: [ 366.558423] r5:c198f080 r4:c315b9cc Jun 24 10:08:26 rpi4 kernel: [ 366.558443] [] (process_one_work) from [] (worker_thread+0x60/0x5c4) Jun 24 10:08:26 rpi4 kernel: [ 366.558459] r10:eff0e600 r9:c1203d00 r8:eff0e618 r7:00000008 r6:eff0e600 r5:c198f094 Jun 24 10:08:26 rpi4 kernel: [ 366.558473] r4:c198f080 Jun 24 10:08:26 rpi4 kernel: [ 366.558492] [] (worker_thread) from [] (kthread+0x170/0x174) Jun 24 10:08:26 rpi4 kernel: [ 366.558509] r10:c1987e74 r9:c198f080 r8:c023dd94 r7:c1a76000 r6:00000000 r5:c198c080 Jun 24 10:08:26 rpi4 kernel: [ 366.558522] r4:c1a3b0c0 Jun 24 10:08:26 rpi4 kernel: [ 366.558540] [] (kthread) from [] (ret_from_fork+0x14/0x28) Jun 24 10:08:26 rpi4 kernel: [ 366.558554] Exception stack(0xc1a77fb0 to 0xc1a77ff8) Jun 24 10:08:26 rpi4 kernel: [ 366.558570] 7fa0: 00000000 00000000 00000000 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.558586] 7fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.558602] 7fe0: 00000000 00000000 00000000 00000000 00000013 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.558619] r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024570c Jun 24 10:08:26 rpi4 kernel: [ 366.558632] r4:c198c080 Jun 24 10:08:26 rpi4 kernel: [ 366.558659] INFO: task kworker/1:2:91 blocked for more than 122 seconds. Jun 24 10:08:26 rpi4 kernel: [ 366.558673] Tainted: G WC 5.10.0-v7l+ #1380 Jun 24 10:08:26 rpi4 kernel: [ 366.558685] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Jun 24 10:08:26 rpi4 kernel: [ 366.558699] task:kworker/1:2 state:D stack: 0 pid: 91 ppid: 2 flags:0x00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.558732] Workqueue: events dbs_work_handler Jun 24 10:08:26 rpi4 kernel: [ 366.558756] Backtrace: Jun 24 10:08:26 rpi4 kernel: [ 366.558785] [] (__schedule) from [] (schedule+0x68/0xe4) Jun 24 10:08:26 rpi4 kernel: [ 366.558806] r10:00000000 r9:00000002 r8:c1205048 r7:c2b14000 r6:c2b15bec r5:c2856c80 Jun 24 10:08:26 rpi4 kernel: [ 366.558820] r4:ffffe000 Jun 24 10:08:26 rpi4 kernel: [ 366.558837] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c) Jun 24 10:08:26 rpi4 kernel: [ 366.558852] r5:ffffe000 r4:c131ccc8 Jun 24 10:08:26 rpi4 kernel: [ 366.558870] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.9+0x344/0x58c) Jun 24 10:08:26 rpi4 kernel: [ 366.558889] [] (__mutex_lock.constprop.9) from [] (__mutex_lock_slowpath+0x1c/0x20) Jun 24 10:08:26 rpi4 kernel: [ 366.558906] r10:d6c01008 r9:c1a910c0 r8:00000018 r7:c1205048 r6:d6c01000 r5:c1a910c0 Jun 24 10:08:26 rpi4 kernel: [ 366.558920] r4:c131ccc8 Jun 24 10:08:26 rpi4 kernel: [ 366.558938] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60) Jun 24 10:08:26 rpi4 kernel: [ 366.558958] [] (mutex_lock) from [] (rpi_firmware_transaction+0x50/0x128) Jun 24 10:08:26 rpi4 kernel: [ 366.558972] r5:c1a910c0 r4:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.558991] [] (rpi_firmware_transaction) from [] (rpi_firmware_property_list+0xbc/0x170) Jun 24 10:08:26 rpi4 kernel: [ 366.559007] r7:c1205048 r6:d6c01000 r5:00001000 r4:d6c01024 Jun 24 10:08:26 rpi4 kernel: [ 366.559026] [] (rpi_firmware_property_list) from [] (rpi_firmware_property+0x70/0x118) Jun 24 10:08:26 rpi4 kernel: [ 366.559043] r10:c58d564c r9:00030002 r8:00000018 r7:c1a910c0 r6:c2b15d08 r5:0000000c Jun 24 10:08:26 rpi4 kernel: [ 366.559057] r4:c58d5640 Jun 24 10:08:26 rpi4 kernel: [ 366.559079] [] (rpi_firmware_property) from [] (raspberrypi_clock_property+0x54/0x7c) Jun 24 10:08:26 rpi4 kernel: [ 366.559096] r10:ffffffde r9:ffffff92 r8:59682f00 r7:00000000 r6:00000000 r5:c2b15d30 Jun 24 10:08:26 rpi4 kernel: [ 366.559110] r4:c1205048 r3:0000000c Jun 24 10:08:26 rpi4 kernel: [ 366.559131] [] (raspberrypi_clock_property) from [] (raspberrypi_fw_get_rate+0x4c/0x70) Jun 24 10:08:26 rpi4 kernel: [ 366.559145] r5:00000000 r4:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.559165] [] (raspberrypi_fw_get_rate) from [] (clk_recalc+0x44/0x84) Jun 24 10:08:26 rpi4 kernel: [ 366.559179] r4:c20ce240 Jun 24 10:08:26 rpi4 kernel: [ 366.559196] [] (clk_recalc) from [] (clk_change_rate+0x108/0x48c) Jun 24 10:08:26 rpi4 kernel: [ 366.559210] r5:00000000 r4:c20ce240 Jun 24 10:08:26 rpi4 kernel: [ 366.559229] [] (clk_change_rate) from [] (clk_core_set_rate_nolock+0x17c/0x1a4) Jun 24 10:08:26 rpi4 kernel: [ 366.559245] r10:ffffffde r9:00000000 r8:c20ce240 r7:59682f00 r6:00000000 r5:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.559258] r4:c20ce240 Jun 24 10:08:26 rpi4 kernel: [ 366.559277] [] (clk_core_set_rate_nolock) from [] (clk_set_rate+0x40/0x98) Jun 24 10:08:26 rpi4 kernel: [ 366.559293] r8:c22c8140 r7:ffffff92 r6:c1205048 r5:59682f00 r4:c22c8140 Jun 24 10:08:26 rpi4 kernel: [ 366.559313] [] (clk_set_rate) from [] (dev_pm_opp_set_rate+0x42c/0x588) Jun 24 10:08:26 rpi4 kernel: [ 366.559328] r5:59682f00 r4:c227da00 Jun 24 10:08:26 rpi4 kernel: [ 366.559348] [] (dev_pm_opp_set_rate) from [] (set_target+0x38/0x3c) Jun 24 10:08:26 rpi4 kernel: [ 366.559365] r10:00000000 r9:00000000 r8:00000009 r7:00000000 r6:c1403e44 r5:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.559379] r4:c227dc00 Jun 24 10:08:26 rpi4 kernel: [ 366.559398] [] (set_target) from [] (__cpufreq_driver_target+0x1bc/0x540) Jun 24 10:08:26 rpi4 kernel: [ 366.559418] [] (__cpufreq_driver_target) from [] (od_dbs_update+0x14c/0x170) Jun 24 10:08:26 rpi4 kernel: [ 366.559434] r10:efee6600 r9:c402eb80 r8:c4022200 r7:c4022200 r6:c402eb80 r5:c402e980 Jun 24 10:08:26 rpi4 kernel: [ 366.559448] r4:c227dc00 Jun 24 10:08:26 rpi4 kernel: [ 366.559468] [] (od_dbs_update) from [] (dbs_work_handler+0x3c/0x64) Jun 24 10:08:26 rpi4 kernel: [ 366.559485] r9:00000000 r8:c131ad64 r7:c227dc00 r6:c402e984 r5:00000000 r4:c402e9b8 Jun 24 10:08:26 rpi4 kernel: [ 366.559505] [] (dbs_work_handler) from [] (process_one_work+0x254/0x584) Jun 24 10:08:26 rpi4 kernel: [ 366.559522] r9:00000000 r8:efee9900 r7:00000040 r6:efee6600 r5:c2b00100 r4:c402e9b8 Jun 24 10:08:26 rpi4 kernel: [ 366.559542] [] (process_one_work) from [] (worker_thread+0x60/0x5c4) Jun 24 10:08:26 rpi4 kernel: [ 366.559559] r10:efee6600 r9:c1203d00 r8:efee6618 r7:00000008 r6:efee6600 r5:c2b00114 Jun 24 10:08:26 rpi4 kernel: [ 366.559572] r4:c2b00100 Jun 24 10:08:26 rpi4 kernel: [ 366.559591] [] (worker_thread) from [] (kthread+0x170/0x174) Jun 24 10:08:26 rpi4 kernel: [ 366.559608] r10:c1941e74 r9:c2b00100 r8:c023dd94 r7:c2b14000 r6:00000000 r5:c28c3040 Jun 24 10:08:26 rpi4 kernel: [ 366.559622] r4:c1b172c0 Jun 24 10:08:26 rpi4 kernel: [ 366.559639] [] (kthread) from [] (ret_from_fork+0x14/0x28) Jun 24 10:08:26 rpi4 kernel: [ 366.559653] Exception stack(0xc2b15fb0 to 0xc2b15ff8) Jun 24 10:08:26 rpi4 kernel: [ 366.559668] 5fa0: 00000000 00000000 00000000 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.559685] 5fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.559700] 5fe0: 00000000 00000000 00000000 00000000 00000013 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.559717] r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c024570c Jun 24 10:08:26 rpi4 kernel: [ 366.559731] r4:c28c3040 Jun 24 10:08:26 rpi4 kernel: [ 366.559780] INFO: task vdr:1168 blocked for more than 122 seconds. Jun 24 10:08:26 rpi4 kernel: [ 366.559794] Tainted: G WC 5.10.0-v7l+ #1380 Jun 24 10:08:26 rpi4 kernel: [ 366.559807] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Jun 24 10:08:26 rpi4 kernel: [ 366.559820] task:vdr state:D stack: 0 pid: 1168 ppid: 1064 flags:0x00000001 Jun 24 10:08:26 rpi4 kernel: [ 366.559847] Backtrace: Jun 24 10:08:26 rpi4 kernel: [ 366.559876] [] (__schedule) from [] (schedule+0x68/0xe4) Jun 24 10:08:26 rpi4 kernel: [ 366.559893] r10:00000001 r9:00000002 r8:c1205048 r7:c46c0000 r6:c46c1bb4 r5:c4be3e00 Jun 24 10:08:26 rpi4 kernel: [ 366.559907] r4:ffffe000 Jun 24 10:08:26 rpi4 kernel: [ 366.559925] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c) Jun 24 10:08:26 rpi4 kernel: [ 366.559939] r5:ffffe000 r4:c12e89b4 Jun 24 10:08:26 rpi4 kernel: [ 366.559958] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.9+0x344/0x58c) Jun 24 10:08:26 rpi4 kernel: [ 366.559977] [] (__mutex_lock.constprop.9) from [] (__mutex_lock_slowpath+0x1c/0x20) Jun 24 10:08:26 rpi4 kernel: [ 366.559994] r10:00000000 r9:00000000 r8:ffffffff r7:c3435440 r6:c1814000 r5:1dcd6500 Jun 24 10:08:26 rpi4 kernel: [ 366.560007] r4:c12e89b4 Jun 24 10:08:26 rpi4 kernel: [ 366.560026] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60) Jun 24 10:08:26 rpi4 kernel: [ 366.560047] [] (mutex_lock) from [] (clk_prepare_lock+0x60/0x110) Jun 24 10:08:26 rpi4 kernel: [ 366.560061] r5:1dcd6500 r4:c13fbdd4 Jun 24 10:08:26 rpi4 kernel: [ 366.560080] [] (clk_prepare_lock) from [] (clk_set_rate_range+0x38/0x158) Jun 24 10:08:26 rpi4 kernel: [ 366.560095] r5:1dcd6500 r4:c39c1bc0 Jun 24 10:08:26 rpi4 kernel: [ 366.560113] [] (clk_set_rate_range) from [] (clk_set_min_rate+0x24/0x30) Jun 24 10:08:26 rpi4 kernel: [ 366.560129] r8:c1814500 r7:c3435440 r6:c1814000 r5:c58d5500 r4:00000006 Jun 24 10:08:26 rpi4 kernel: [ 366.560208] [] (clk_set_min_rate) from [] (vc4_atomic_complete_commit+0x4c4/0x680 [vc4]) Jun 24 10:08:26 rpi4 kernel: [ 366.560335] [] (vc4_atomic_complete_commit [vc4]) from [] (vc4_atomic_commit+0x17c/0x1cc [vc4]) Jun 24 10:08:26 rpi4 kernel: [ 366.560352] r10:00000000 r9:00000000 r8:c1814500 r7:00000000 r6:00000000 r5:c1814000 Jun 24 10:08:26 rpi4 kernel: [ 366.560366] r4:c58d5500 r3:00000001 Jun 24 10:08:26 rpi4 kernel: [ 366.560595] [] (vc4_atomic_commit [vc4]) from [] (drm_atomic_commit+0x54/0x60 [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.560612] r9:00000000 r8:00000000 r7:00000000 r6:c1814000 r5:c58d5500 r4:00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.560861] [] (drm_atomic_commit [drm]) from [] (drm_atomic_helper_disable_plane+0xb4/0xec [drm_kms_helper]) Jun 24 10:08:26 rpi4 kernel: [ 366.560877] r7:00000000 r6:c46c1dac r5:00000000 r4:c58d5500 Jun 24 10:08:26 rpi4 kernel: [ 366.561120] [] (drm_atomic_helper_disable_plane [drm_kms_helper]) from [] (__setplane_atomic+0x120/0x144 [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.561136] r7:00000000 r6:00000000 r5:00000000 r4:c32e0240 Jun 24 10:08:26 rpi4 kernel: [ 366.561454] [] (__setplane_atomic [drm]) from [] (drm_mode_setplane+0x190/0x348 [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.561471] r10:c32e0240 r9:00000000 r8:00000000 r7:c1205048 r6:c32e0270 r5:00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.561484] r4:c46c1dac Jun 24 10:08:26 rpi4 kernel: [ 366.561803] [] (drm_mode_setplane [drm]) from [] (drm_ioctl_kernel+0xb8/0xfc [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.561820] r10:c46c1e64 r9:bf51976c r8:00000002 r7:00000000 r6:c5894500 r5:c1814000 Jun 24 10:08:26 rpi4 kernel: [ 366.561833] r4:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.562151] [] (drm_ioctl_kernel [drm]) from [] (drm_ioctl+0x1e4/0x3a8 [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.562169] r10:00000030 r9:c46c1e64 r8:c5894500 r7:000000b7 r6:bf51976c r5:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.562182] r4:bf531bac Jun 24 10:08:26 rpi4 kernel: [ 366.562353] [] (drm_ioctl [drm]) from [] (sys_ioctl+0x1d4/0x8ec) Jun 24 10:08:26 rpi4 kernel: [ 366.562370] r10:0000000b r9:c46c0000 r8:00000000 r7:c3b89780 r6:c3b89781 r5:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.562383] r4:c03064b7 Jun 24 10:08:26 rpi4 kernel: [ 366.562403] [] (sys_ioctl) from [] (ret_fast_syscall+0x0/0x28) Jun 24 10:08:26 rpi4 kernel: [ 366.562417] Exception stack(0xc46c1fa8 to 0xc46c1ff0) Jun 24 10:08:26 rpi4 kernel: [ 366.562434] 1fa0: b6fe3968 bec8979c 0000000b c03064b7 bec8979c 00000000 Jun 24 10:08:26 rpi4 kernel: [ 366.562451] 1fc0: b6fe3968 bec8979c c03064b7 00000036 00189000 001e3298 0018c450 60d43cb2 Jun 24 10:08:26 rpi4 kernel: [ 366.562466] 1fe0: b3f0108c bec8977c b3ee788c b6ae051c Jun 24 10:08:26 rpi4 kernel: [ 366.562484] r10:00000036 r9:c46c0000 r8:c0200204 r7:00000036 r6:c03064b7 r5:bec8979c Jun 24 10:08:26 rpi4 kernel: [ 366.562497] r4:b6fe3968 Jun 24 10:08:26 rpi4 kernel: [ 366.562512] INFO: task vdr:1181 blocked for more than 122 seconds. Jun 24 10:08:26 rpi4 kernel: [ 366.562526] Tainted: G WC 5.10.0-v7l+ #1380 Jun 24 10:08:26 rpi4 kernel: [ 366.562539] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Jun 24 10:08:26 rpi4 kernel: [ 366.562552] task:vdr state:D stack: 0 pid: 1181 ppid: 1064 flags:0x00000001 Jun 24 10:08:26 rpi4 kernel: [ 366.562578] Backtrace: Jun 24 10:08:26 rpi4 kernel: [ 366.562607] [] (__schedule) from [] (schedule+0x68/0xe4) Jun 24 10:08:26 rpi4 kernel: [ 366.562624] r10:00000002 r9:c5899cec r8:ffffe000 r7:c5898000 r6:00000000 r5:c525ec80 Jun 24 10:08:26 rpi4 kernel: [ 366.562638] r4:ffffe000 Jun 24 10:08:26 rpi4 kernel: [ 366.562655] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c) Jun 24 10:08:26 rpi4 kernel: [ 366.562670] r5:c5899dac r4:c1814104 Jun 24 10:08:26 rpi4 kernel: [ 366.562688] [] (schedule_preempt_disabled) from [] (__ww_mutex_lock.constprop.7+0x478/0x860) Jun 24 10:08:26 rpi4 kernel: [ 366.562708] [] (__ww_mutex_lock.constprop.7) from [] (__ww_mutex_lock_slowpath+0x20/0x24) Jun 24 10:08:26 rpi4 kernel: [ 366.562725] r10:c5899e74 r9:c1205048 r8:c5894500 r7:00000000 r6:c1814104 r5:c5899dac Jun 24 10:08:26 rpi4 kernel: [ 366.562738] r4:c1814104 Jun 24 10:08:26 rpi4 kernel: [ 366.562756] [] (__ww_mutex_lock_slowpath) from [] (ww_mutex_lock+0x64/0x70) Jun 24 10:08:26 rpi4 kernel: [ 366.562924] [] (ww_mutex_lock) from [] (drm_modeset_lock+0x50/0x118 [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.562938] r5:00000000 r4:c5899dac Jun 24 10:08:26 rpi4 kernel: [ 366.563253] [] (drm_modeset_lock [drm]) from [] (drm_modeset_lock_all_ctx+0x24/0xe8 [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.563269] r7:00000000 r6:c1814000 r5:c5899dac r4:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.563583] [] (drm_modeset_lock_all_ctx [drm]) from [] (drm_mode_obj_get_properties_ioctl+0xf0/0x19c [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.563600] r9:c1205048 r8:c5894500 r7:00000000 r6:c5899e64 r5:c1814000 r4:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.563915] [] (drm_mode_obj_get_properties_ioctl [drm]) from [] (drm_ioctl_kernel+0xb8/0xfc [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.563932] r10:c5899e64 r9:bf5166cc r8:00000000 r7:00000000 r6:c5894500 r5:c1814000 Jun 24 10:08:26 rpi4 kernel: [ 366.563946] r4:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.564260] [] (drm_ioctl_kernel [drm]) from [] (drm_ioctl+0x1e4/0x3a8 [drm]) Jun 24 10:08:26 rpi4 kernel: [ 366.564277] r10:00000020 r9:c5899e64 r8:c5894500 r7:000000b9 r6:bf5166cc r5:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.564290] r4:bf531bcc Jun 24 10:08:26 rpi4 kernel: [ 366.564458] [] (drm_ioctl [drm]) from [] (sys_ioctl+0x1d4/0x8ec) Jun 24 10:08:26 rpi4 kernel: [ 366.564475] r10:0000000b r9:c5898000 r8:00000000 r7:c3b89780 r6:c3b89781 r5:c1205048 Jun 24 10:08:26 rpi4 kernel: [ 366.564489] r4:c02064b9 Jun 24 10:08:26 rpi4 kernel: [ 366.564508] [] (sys_ioctl) from [] (ret_fast_syscall+0x0/0x28) Jun 24 10:08:26 rpi4 kernel: [ 366.564522] Exception stack(0xc5899fa8 to 0xc5899ff0) Jun 24 10:08:26 rpi4 kernel: [ 366.564538] 9fa0: 000002d0 a6efbcc0 0000000b c02064b9 a6efbcc0 a6efbce0 Jun 24 10:08:26 rpi4 kernel: [ 366.564555] 9fc0: 000002d0 a6efbcc0 c02064b9 00000036 c02064b9 eeeeeeee 00000042 b55e42d4 Jun 24 10:08:26 rpi4 kernel: [ 366.564570] 9fe0: b3f0108c a6efbc94 b3ee788c b6ae051c Jun 24 10:08:26 rpi4 kernel: [ 366.564587] r10:00000036 r9:c5898000 r8:c0200204 r7:00000036 r6:c02064b9 r5:a6efbcc0 Jun 24 10:08:26 rpi4 kernel: [ 366.564601] r4:000002d0