Sporadische Aussetzer bei Musikwidergabe

[matschie]

Hallo,

erstmal vielen Dank für das tolle Projekt. Nachdem ich einige Zeit mit max2play, picoreplayer etc. rumexperimentiert habe, habe ich mich entschieden auf den MS4H zu setzen.
Die Einrichtung hat auch wunderbar geklappt, Events funktionieren, KNX Anbindung funktioniert. Der Powermanager läuft. Es wäre alles so schön, wenn nicht eine nervige Kleinigkeit wäre:

Bei der Musikwidergabe habe ich immer mal wieder Aussetzer, ca. alle 30-40 Sekunden.

Und zwar egal ob via Spotify Connect (Hier verliert er die Verbindung zum Gerät komplett. Er spielt zwar weiter, aber ich kann es vom Handy aus nicht mehr steuern), via Airplay (shairtunes2) oder sogar Internetradio.

shairtunes2 bietet ja ein detailliertes Log an, hier steht dann was von "empty frame" und "packet recovered". Genauen Wortlaut müsste ich nochmal nachschauen.

Das ganze System lief auf einem NUC J4005 mit USB Soundkarte DeLock 7.1 auf Debian-Basis einige Zeit einigermaßen Problemlos, zumindest das Internetradio. AirPlay und Spotify Connect hatten immer Problemchen, hatte aber lange keine Zeit mich damit zu beschäftigen.
Nachdem das jetzt häufiger aufgetreten ist habe ich mal Testweise auf einem RPi 3B+ ein DietPi installiert und das System neu eingerichtet. Tada - exakt das gleiche Fehlerbild.
Also denke ich, dass da nicht die Konfiguration von MS4H hintersteckt. Als Hardware sind ja nur noch die Soundkarte sowie die Verstärker gleich geblieben.

Der Verstärker wird nicht sonderlich warm (SMSL SA50), hier schließe ich die Fehler ebenfalls aus. Bliebe die Soundkarte. Könnte das sein?

Ich vermute allerdings eher, dass er Probleme mit der Netzwerkverbindung bzw. Internetverbindung hat, habe aber keine Idee wo das herkommen kann. Anbindung via Kabel. Netzwerkinfrastruktur ist UniFi mit einer Dream Machine Pro als Router im Internet der Deutschen Glasfaser. mDNS ist aktiviert, weder Handy noch MS4H sind in einem VLAN.

Ich habe hier mal von Problemen bei der DNS gelesen wodurch viele Anfragen gestellt wurden, kann das hier auch ein Problem sein?

Kennt sonst jemand dieses Phänomen oder hat eine Idee wo das herkommen kann?
Sofern das hilfreich ist kann ich auch gerne weitere Logs / Screenshots o.ä. liefern.

[hismastersvoice]

Das Problem mit der DNS hat nur zugetroffen wenn man einen PiHole betreiben hat und ist behoben.

Es können natürlich die Soundkarten sein, ich empfehle bei mehr als einer Karte immer einen MTT USB Hub.
LogiLink UA0148 USB 2.0 HUB 7-port, incl. Power Display, Full Metal Housing: Amazon.de: Computer & Zubehör

In der Konsole kannst du über den Befehl dmesg schauen ob z.b die Soundkarten ein Bandbreiten Problem haben.



Du hast es auf 2 Maschinen, und mehrere hundert Installationen haben das Problem nicht, somit würde ich auch dein Netzwerk nicht ausschließen.
Du kannst unter /var/log/squeezeboxserverdern server.log anschauen um zu sehen was für Meldungen kommen. Vielleicht kommen wir dem Thema dann näher.



Du kannst auch den Buffer kontrollieren und ggf etwas erhöhen.

[matschie]

Hallo und erstmal vielen Dank.Ich musste erstmal wieder Zeit finden, zu testen und zu loggen.
Nach knapp 7 Minuten Internetradio ist es das erste mal aufgetreten. Ab dann alle 20-30 sek. Nach einiger Zeit dann erstmal wieder gar nicht.
Ich konnte das auch nicht direkt

Es ist nur eine Soundkarte aktuell.

Den Puffer fürs Internetradio hab ich erhöht, das hat aber keine Besserung ergeben.

Hier mal ein Auszug aus dem Log. Ich habe mal einige Punkte auf "debug" gestellt in der Hoffnung mehr Info zu bekommen. In der Standardeinstellung gab es keine relevanten Einträge. Ich konnte den Aussetzer auch keine widerkehrenden Eintrag zuordnen.

[22-11-13 13:38:07.6154] Slim::Control::Request::dump (2359) Result: 0. [displaytype] = [none]
[22-11-13 13:38:07.6160] Slim::Control::Request::dump (2359) Result: 0. [isplayer] = [1]
[22-11-13 13:38:07.6166] Slim::Control::Request::dump (2359) Result: 0. [canpoweroff] = [1]
[22-11-13 13:38:07.6172] Slim::Control::Request::dump (2359) Result: 0. [connected] = [1]
[22-11-13 13:38:07.6177] Slim::Control::Request::dump (2359) Result: 0. [firmware] = [v1.9.9-1412]
[22-11-13 13:38:07.6183] Slim::Control::Request::executeDone (1918) 0
[22-11-13 13:38:08.6140] Slim::Control::Request::dump (2336) Request: Query [players] from JSONRPC (Dispatchable)
[22-11-13 13:38:08.6144] Slim::Control::Request::dump (2340) Param: [_index] = [0]
[22-11-13 13:38:08.6148] Slim::Control::Request::dump (2340) Param: [_quantity] = undef
[22-11-13 13:38:08.6163] Slim::Control::Request::dump (2336) Request: Query [players] from JSONRPC (Done)
[22-11-13 13:38:08.6167] Slim::Control::Request::dump (2340) Param: [_index] = [0]
[22-11-13 13:38:08.6171] Slim::Control::Request::dump (2340) Param: [_quantity] = undef
[22-11-13 13:38:08.6176] Slim::Control::Request::dump (2372) Result: [count] = [1]
[22-11-13 13:38:08.6181] Slim::Control::Request::dump (2349) Result: [players_loop] is loop with 1 elements:
[22-11-13 13:38:08.6186] Slim::Control::Request::dump (2359) Result: 0. [playerindex] = [0]
[22-11-13 13:38:08.6192] Slim::Control::Request::dump (2359) Result: 0. [playerid] = [01:27:eb:d1:42:a2]
[22-11-13 13:38:08.6201] Slim::Utils::Misc::msg (1325) Warning: [13:38:08.6195] Use of uninitialized value in join or string at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender.pm line 165.
[22-11-13 13:38:08.6211] Slim::Utils::Misc::msg (1325) Warning: [13:38:08.6206] Use of uninitialized value in join or string at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender.pm line 165.
[22-11-13 13:38:08.6213] Slim::Control::Request::dump (2359) Result: 0. [uuid] = []
[22-11-13 13:38:08.6219] Slim::Control::Request::dump (2359) Result: 0. [ip] = [192.168.177.210:57730]
[22-11-13 13:38:08.6225] Slim::Control::Request::dump (2359) Result: 0. [name] = [Küche]
[22-11-13 13:38:08.6230] Slim::Control::Request::dump (2359) Result: 0. [seq_no] = [0]
[22-11-13 13:38:08.6236] Slim::Control::Request::dump (2359) Result: 0. [model] = [squeezelite]
[22-11-13 13:38:08.6241] Slim::Control::Request::dump (2359) Result: 0. [modelname] = [MusicServer4Home-Player]
[22-11-13 13:38:08.6246] Slim::Control::Request::dump (2359) Result: 0. [power] = [1]
[22-11-13 13:38:08.6251] Slim::Control::Request::dump (2359) Result: 0. [isplaying] = [1]
[22-11-13 13:38:08.6257] Slim::Control::Request::dump (2359) Result: 0. [displaytype] = [none]
[22-11-13 13:38:08.6262] Slim::Control::Request::dump (2359) Result: 0. [isplayer] = [1]
[22-11-13 13:38:08.6268] Slim::Control::Request::dump (2359) Result: 0. [canpoweroff] = [1]
[22-11-13 13:38:08.6273] Slim::Control::Request::dump (2359) Result: 0. [connected] = [1]
[22-11-13 13:38:08.6280] Slim::Control::Request::dump (2359) Result: 0. [firmware] = [v1.9.9-1412]
[22-11-13 13:38:08.6286] Slim::Control::Request::executeDone (1918) 0
[22-11-13 13:38:09.6095] Slim::Control::Request::dump (2336) Request: Query [players] from JSONRPC (Dispatchable)
[22-11-13 13:38:09.6103] Slim::Control::Request::dump (2340) Param: [_index] = [0]
[22-11-13 13:38:09.6107] Slim::Control::Request::dump (2340) Param: [_quantity] = undef
[22-11-13 13:38:09.6123] Slim::Control::Request::dump (2336) Request: Query [players] from JSONRPC (Done)
[22-11-13 13:38:09.6127] Slim::Control::Request::dump (2340) Param: [_index] = [0]
[22-11-13 13:38:09.6131] Slim::Control::Request::dump (2340) Param: [_quantity] = undef
[22-11-13 13:38:09.6135] Slim::Control::Request::dump (2372) Result: [count] = [1]
[22-11-13 13:38:09.6140] Slim::Control::Request::dump (2349) Result: [players_loop] is loop with 1 elements:
[22-11-13 13:38:09.6144] Slim::Control::Request::dump (2359) Result: 0. [playerindex] = [0]
[22-11-13 13:38:09.6148] Slim::Control::Request::dump (2359) Result: 0. [playerid] = [01:27:eb:d1:42:a2]
[22-11-13 13:38:09.6154] Slim::Utils::Misc::msg (1325) Warning: [13:38:09.6150] Use of uninitialized value in join or string at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender.pm line 165.
[22-11-13 13:38:09.6161] Slim::Utils::Misc::msg (1325) Warning: [13:38:09.6157] Use of uninitialized value in join or string at /usr/share/squeezeboxserver/CPAN/Log/Log4perl/Appender.pm line 165.
[22-11-13 13:38:09.6163] Slim::Control::Request::dump (2359) Result: 0. [uuid] = []
[22-11-13 13:38:09.6167] Slim::Control::Request::dump (2359) Result: 0. [ip] = [192.168.177.210:57730]
[22-11-13 13:38:09.6171] Slim::Control::Request::dump (2359) Result: 0. [name] = [Küche]
[22-11-13 13:38:09.6175] Slim::Control::Request::dump (2359) Result: 0. [seq_no] = [0]
[22-11-13 13:38:09.6179] Slim::Control::Request::dump (2359) Result: 0. [model] = [squeezelite]
[22-11-13 13:38:09.6183] Slim::Control::Request::dump (2359) Result: 0. [modelname] = [MusicServer4Home-Player]
[22-11-13 13:38:09.6187] Slim::Control::Request::dump (2359) Result: 0. [power] = [1]
[22-11-13 13:38:09.6191] Slim::Control::Request::dump (2359) Result: 0. [isplaying] = [1]
[22-11-13 13:38:09.6195] Slim::Control::Request::dump (2359) Result: 0. [displaytype] = [none]
[22-11-13 13:38:09.6200] Slim::Control::Request::dump (2359) Result: 0. [isplayer] = [1]
[22-11-13 13:38:09.6204] Slim::Control::Request::dump (2359) Result: 0. [canpoweroff] = [1]
[22-11-13 13:38:09.6208] Slim::Control::Request::dump (2359) Result: 0. [connected] = [1]
[22-11-13 13:38:09.6214] Slim::Control::Request::dump (2359) Result: 0. [firmware] = [v1.9.9-1412]
[22-11-13 13:38:09.6219] Slim::Control::Request::executeDone (1918) 0
[22-11-13 13:38:09.7966] Slim::Control::Request::dump (2336) Request: Query [01:27:eb:d1:42:a2->status] from JSONRPC (Dispatchable)
[22-11-13 13:38:09.7977] Slim::Control::Request::dump (2340) Param: [_index] = [-]
[22-11-13 13:38:09.7986] Slim::Control::Request::dump (2340) Param: [_quantity] = [1]
[22-11-13 13:38:09.7995] Slim::Control::Request::dump (2340) Param: [tags] = [uB]
[22-11-13 13:38:09.8101] Slim::Control::Request::dump (2336) Request: Query [01:27:eb:d1:42:a2->status] from JSONRPC (Done)
[22-11-13 13:38:09.8106] Slim::Control::Request::dump (2340) Param: [_index] = [-]
[22-11-13 13:38:09.8112] Slim::Control::Request::dump (2340) Param: [_quantity] = [1]
[22-11-13 13:38:09.8122] Slim::Control::Request::dump (2340) Param: [tags] = [uB]
[22-11-13 13:38:09.8132] Slim::Control::Request::dump (2372) Result: [player_name] = [Küche]
[22-11-13 13:38:09.8141] Slim::Control::Request::dump (2372) Result: [player_connected] = [1]
[22-11-13 13:38:09.8151] Slim::Control::Request::dump (2372) Result: [player_ip] = [192.168.177.210:57730]
[22-11-13 13:38:09.8160] Slim::Control::Request::dump (2372) Result: [power] = [1]
[22-11-13 13:38:09.8169] Slim::Control::Request::dump (2372) Result: [signalstrength] = [0]
[22-11-13 13:38:09.8179] Slim::Control::Request::dump (2372) Result: [mode] = [play]
[22-11-13 13:38:09.8188] Slim::Control::Request::dump (2372) Result: [remote] = [1]
[22-11-13 13:38:09.8199] Slim::Control::Request::dump (2372) Result: [current_title] = [Wir sind der Westen]
[22-11-13 13:38:09.8209] Slim::Control::Request::dump (2372) Result: [time] = [8.85737403106689]
[22-11-13 13:38:09.8219] Slim::Control::Request::dump (2372) Result: [rate] = [1]
[22-11-13 13:38:09.8228] Slim::Control::Request::dump (2372) Result: [mixer volume] = [32]
[22-11-13 13:38:09.8237] Slim::Control::Request::dump (2372) Result: [playlist repeat] = [0]
[22-11-13 13:38:09.8246] Slim::Control::Request::dump (2372) Result: [playlist shuffle] = [0]
[22-11-13 13:38:09.8254] Slim::Control::Request::dump (2372) Result: [playlist mode] = [off]
[22-11-13 13:38:09.8262] Slim::Control::Request::dump (2372) Result: [seq_no] = [0]
[22-11-13 13:38:09.8270] Slim::Control::Request::dump (2372) Result: [playlist_cur_index] = [0]
[22-11-13 13:38:09.8279] Slim::Control::Request::dump (2372) Result: [playlist_timestamp] = [1668345909.9956]
[22-11-13 13:38:09.8287] Slim::Control::Request::dump (2372) Result: [playlist_tracks] = [1]
[22-11-13 13:38:09.8295] Slim::Control::Request::dump (2372) Result: [digital_volume_control] = [1]
[22-11-13 13:38:09.8313] Slim::Control::Request::dump (2372) Result: [remoteMeta] = [{
# tied Tie::IxHash
id => -92_779_024,
title => "Wir sind der Westen",
url => "https://f111.rndfnk.com/ard/wdr/wdr2/muensterland/mp3/128/stream.mp3?cid=01FBS05PV4NHEGHGFMHSEQ9C5V&sid=2HUiDLoOPhLooA4myLPsGiLnap9&token=R6eULfEAzzhzRSo-1FeG_lFzIL_w956nWO7CTUwdpbo&tvf=9u5ehnA7JxdmMTExLnJuZGZuay5jb20",
}]
[22-11-13 13:38:09.8322] Slim::Control::Request::dump (2349) Result: [playlist_loop] is loop with 1 elements:
[22-11-13 13:38:09.8329] Slim::Control::Request::dump (2359) Result: 0. [url] = [https://f111.rndfnk.com/ard/wdr/wdr2/muensterland/mp3/128/stream.mp3?cid=01FBS05PV4NHEGHGFMHSEQ9C5V&sid=2HUiDLoOPhLooA4myLPsGiLnap9&token=R6eULfEAzzhzRSo-1FeG_lFzIL_w956nWO7CTUwdpbo&tvf=9u5ehnA7JxdmMTExLnJuZGZuay5jb20]
[22-11-13 13:38:09.8337] Slim::Control::Request::dump (2359) Result: 0. [playlist index] = [0]
[22-11-13 13:38:09.8345] Slim::Control::Request::dump (2359) Result: 0. [id] = [-92779024]
[22-11-13 13:38:09.8352] Slim::Control::Request::dump (2359) Result: 0. [title] = [Wir sind der Westen]
[22-11-13 13:38:09.8361] Slim::Control::Request::executeDone (1918) 0​

Bei ShairTunes2 gibt es folgende Nachricht:

13:52:03.675402] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63146 rtptime:2197988910 (W:63217 R:63109)
[13:52:03.675578] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63147 rtptime:2197989262 (W:63217 R:63110)
[13:52:03.675749] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63148 rtptime:2197989614 (W:63217 R:63111)
[13:52:03.675919] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63149 rtptime:2197989966 (W:63217 R:63112)
[13:52:03.676088] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63150 rtptime:2197990318 (W:63217 R:63113)
[13:52:03.676258] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63151 rtptime:2197990670 (W:63217 R:63114)
[13:52:03.676430] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63152 rtptime:2197991022 (W:63217 R:63115)
[13:52:03.676601] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63153 rtptime:2197991374 (W:63217 R:63116)
[13:52:03.676769] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63154 rtptime:2197991726 (W:63217 R:63117)
[13:52:03.676937] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63155 rtptime:2197992078 (W:63217 R:63118)
[13:52:03.677107] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63156 rtptime:2197992430 (W:63217 R:63119)
[13:52:03.677276] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63157 rtptime:2197992782 (W:63217 R:63120)
[13:52:03.677443] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63158 rtptime:2197993134 (W:63217 R:63121)
[13:52:03.677610] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63159 rtptime:2197993486 (W:63217 R:63122)
[13:52:03.677778] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63160 rtptime:2197993838 (W:63217 R:63123)
[13:52:03.677948] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63161 rtptime:2197994190 (W:63217 R:63124)
[13:52:03.678118] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63162 rtptime:2197994542 (W:63217 R:63125)
[13:52:03.678287] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63163 rtptime:2197994894 (W:63217 R:63126)
[13:52:03.678456] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63164 rtptime:2197995246 (W:63217 R:63127)
[13:52:03.678624] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63165 rtptime:2197995598 (W:63217 R:63128)
[13:52:03.678791] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63166 rtptime:2197995950 (W:63217 R:63129)
[13:52:03.678957] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63167 rtptime:2197996302 (W:63217 R:63130)
[13:52:03.679126] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63168 rtptime:2197996654 (W:63217 R:63131)
[13:52:03.679294] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63169 rtptime:2197997006 (W:63217 R:63132)
[13:52:03.679478] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63170 rtptime:2197997358 (W:63217 R:63133)
[13:52:03.679646] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63171 rtptime:2197997710 (W:63217 R:63134)
[13:52:03.679848] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63172 rtptime:2197998062 (W:63217 R:63135)
[13:52:03.680023] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63173 rtptime:2197998414 (W:63217 R:63136)
[13:52:03.680188] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63174 rtptime:2197998766 (W:63217 R:63137)
[13:52:03.680353] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63175 rtptime:2197999118 (W:63217 R:63138)
[13:52:03.680517] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63176 rtptime:2197999470 (W:63217 R:63139)
[13:52:03.680684] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63177 rtptime:2197999822 (W:63217 R:63140)
[13:52:03.680870] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63178 rtptime:2198000174 (W:63217 R:63141)
[13:52:03.681212] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63179 rtptime:2198000526 (W:63217 R:63142)
[13:52:03.681621] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63180 rtptime:2198000878 (W:63217 R:63143)
[13:52:03.682009] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63181 rtptime:2198001230 (W:63217 R:63143)
[13:52:03.682403] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63182 rtptime:2198001582 (W:63217 R:63144)
[13:52:03.682793] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63209 rtptime:2198011086 (W:63217 R:63145)
[13:52:03.683218] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63210 rtptime:2198011438 (W:63217 R:63146)
[13:52:03.864219] rtp_request_resend:901 resend request [W:63238 R:63183 first=63183 last=63183]
[13:52:03.864546] rtp_request_resend:901 resend request [W:63238 R:63183 first=63184 last=63184]
[13:52:03.864764] rtp_request_resend:901 resend request [W:63238 R:63183 first=63185 last=63185]
[13:52:03.864888] rtp_request_resend:901 resend request [W:63238 R:63183 first=63186 last=63186]
[13:52:03.865008] rtp_request_resend:901 resend request [W:63238 R:63183 first=63187 last=63187]
[13:52:03.865105] rtp_request_resend:901 resend request [W:63238 R:63183 first=63188 last=63188]
[13:52:03.865154] rtp_request_resend:901 resend request [W:63238 R:63183 first=63189 last=63189]
[13:52:03.865203] rtp_request_resend:901 resend request [W:63238 R:63183 first=63190 last=63190]
[13:52:03.865252] rtp_request_resend:901 resend request [W:63238 R:63183 first=63191 last=63191]
[13:52:03.865300] rtp_request_resend:901 resend request [W:63238 R:63183 first=63192 last=63192]
[13:52:03.865350] rtp_request_resend:901 resend request [W:63238 R:63183 first=63193 last=63193]
[13:52:03.865398] rtp_request_resend:901 resend request [W:63238 R:63183 first=63194 last=63194]
[13:52:03.865447] rtp_request_resend:901 resend request [W:63238 R:63183 first=63195 last=63195]
[13:52:03.865511] rtp_request_resend:901 resend request [W:63238 R:63183 first=63196 last=63196]
[13:52:03.865559] rtp_request_resend:901 resend request [W:63238 R:63183 first=63197 last=63197]
[13:52:03.865666] rtp_request_resend:901 resend request [W:63238 R:63183 first=63198 last=63198]
[13:52:03.868577] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63183 rtptime:2198001934 (W:63244 R:63183)
[13:52:03.868806] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63184 rtptime:2198002286 (W:63244 R:63183)
[13:52:03.869527] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63185 rtptime:2198002638 (W:63244 R:63183)
[13:52:03.869729] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63186 rtptime:2198002990 (W:63244 R:63183)
[13:52:03.869898] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63187 rtptime:2198003342 (W:63244 R:63183)
[13:52:03.870064] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63188 rtptime:2198003694 (W:63244 R:63183)
[13:52:03.870231] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63189 rtptime:2198004046 (W:63244 R:63183)
[13:52:03.871547] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63190 rtptime:2198004398 (W:63244 R:63183)
[13:52:03.871715] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63191 rtptime:2198004750 (W:63244 R:63183)
[13:52:03.871880] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63192 rtptime:2198005102 (W:63244 R:63183)
[13:52:03.872044] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63193 rtptime:2198005454 (W:63244 R:63183)
[13:52:03.872207] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63194 rtptime:2198005806 (W:63244 R:63183)
[13:52:03.872372] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63195 rtptime:2198006158 (W:63244 R:63183)
[13:52:03.872540] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63196 rtptime:2198006510 (W:63244 R:63183)
[13:52:03.872703] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63197 rtptime:2198006862 (W:63244 R:63183)
[13:52:03.872871] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63198 rtptime:2198007214 (W:63244 R:63183)
[13:52:03.872892] buffer_put_packet:648 [0x1f22970]: fill [level:62] [W:63244 R:63183]
[13:52:03.881784] rtp_request_resend:901 resend request [W:63244 R:63183 first=63199 last=63199]
[13:52:03.882133] rtp_request_resend:901 resend request [W:63244 R:63184 first=63200 last=63200]
[13:52:03.882409] rtp_request_resend:901 resend request [W:63244 R:63185 first=63201 last=63201]
[13:52:03.882618] rtp_request_resend:901 resend request [W:63244 R:63186 first=63202 last=63202]
[13:52:03.882856] rtp_request_resend:901 resend request [W:63244 R:63187 first=63203 last=63203]
[13:52:03.883063] rtp_request_resend:901 resend request [W:63244 R:63188 first=63204 last=63204]
[13:52:03.883302] rtp_request_resend:901 resend request [W:63244 R:63189 first=63205 last=63205]
[13:52:03.883509] rtp_request_resend:901 resend request [W:63244 R:63190 first=63206 last=63206]
[13:52:03.883748] rtp_request_resend:901 resend request [W:63244 R:63191 first=63207 last=63207]
[13:52:03.883948] rtp_request_resend:901 resend request [W:63244 R:63192 first=63208 last=63208]
[13:52:03.884352] rtp_request_resend:901 resend request [W:63244 R:63195 first=63211 last=63211]
[13:52:03.884552] rtp_request_resend:901 resend request [W:63244 R:63196 first=63212 last=63212]
[13:52:03.884786] rtp_request_resend:901 resend request [W:63244 R:63197 first=63213 last=63213]
[13:52:03.884990] rtp_request_resend:901 resend request [W:63244 R:63198 first=63214 last=63214]
[13:52:03.886794] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63199 rtptime:2198007566 (W:63246 R:63199)
[13:52:03.887196] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63200 rtptime:2198007918 (W:63246 R:63199)
[13:52:03.887583] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63201 rtptime:2198008270 (W:63246 R:63199)
[13:52:03.887978] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63202 rtptime:2198008622 (W:63246 R:63199)
[13:52:03.888362] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63203 rtptime:2198008974 (W:63246 R:63199)
[13:52:03.888747] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63204 rtptime:2198009326 (W:63246 R:63199)
[13:52:03.889131] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63205 rtptime:2198009678 (W:63246 R:63199)
[13:52:03.889517] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63206 rtptime:2198010030 (W:63246 R:63199)
[13:52:03.889966] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63207 rtptime:2198010382 (W:63246 R:63199)
[13:52:03.890357] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63208 rtptime:2198010734 (W:63246 R:63199)
[13:52:03.890744] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63211 rtptime:2198011790 (W:63246 R:63199)
[13:52:03.891131] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63212 rtptime:2198012142 (W:63246 R:63199)
[13:52:03.891520] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63213 rtptime:2198012494 (W:63246 R:63199)
[13:52:03.891940] buffer_put_packet:641 [0x1f22970]: packet recovered seqno:63214 rtptime:2198012846 (W:63246 R:63199)
[13:52:04.173699] _buffer_get_frame:988 [0x1f22970]: drain [level:2 gap:1000] [W:63281 R:63279] [R:298 S:465 F:0]
[13:52:04.575076] rtp_thread_func:770 [0x1f22970]: sync packet rtp_latency:2197976040 rtp:2198053215 remote ntp:83b4b925b48af5fd, local time 1631241 (now:1631245)
[13:52:06.579888] rtp_thread_func:770 [0x1f22970]: sync packet rtp_latency:2198064421 rtp:2198141596 remote ntp:83b4b927b5982880, local time 1633245 (now:1633250)
[13:52:07.857075] buffer_put_packet:648 [0x1f22970]: fill [level:1] [W:63742 R:63742]
[13:52:08.266649] _buffer_get_frame:988 [0x1f22970]: drain [level:2 gap:995] [W:63793 R:63791] [R:298 S:465 F:0]
[13:52:08.578908] rtp_thread_func:770 [0x1f22970]: sync packet rtp_latency:2198152569 rtp:2198229744 remote ntp:83b4b929b54a42ae, local time 1635244 (now:1635249)
[13:52:08.579381] rtp_request_timing:865 [0x1f22970]: timing request now:1635249 (port: 59278)
[13:52:08.583201] rtp_thread_func:846 [0x1f22970]: Timing references local:1635249, remote:83b4b929b66c1ee9 (delta:-1, sum:-6, adjust:16, gaps:0)
[13:52:10.429410] _buffer_get_frame:979 [0x1f22970]: start silence (late 3 ms) [W:63936 R:63937]
[13:52:10.429585] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63937 R:63937)
[13:52:10.445981] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63938 R:63938)
[13:52:10.446131] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63939 R:63939)
[13:52:10.462304] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63940 R:63940)
[13:52:10.462653] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63941 R:63941)
[13:52:10.478859] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63942 R:63942)
[13:52:10.479018] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63943 R:63943)
[13:52:10.495131] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63944 R:63944)
[13:52:10.495334] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63945 R:63945)
[13:52:10.511479] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63946 R:63946)
[13:52:10.511819] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63947 R:63947)
[13:52:10.528025] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63948 R:63948)
[13:52:10.528284] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63949 R:63949)
[13:52:10.544487] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63950 R:63950)
[13:52:10.544761] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63951 R:63951)
[13:52:10.561003] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63952 R:63952)
[13:52:10.561866] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63953 R:63953)
[13:52:10.562479] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63954 R:63954)
[13:52:10.579160] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63955 R:63955)
[13:52:10.579821] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63956 R:63956)
[13:52:10.596338] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63957 R:63957)
[13:52:10.596793] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63958 R:63958)
[13:52:10.613143] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63959 R:63959)
[13:52:10.613630] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63960 R:63960)
[13:52:10.629998] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63961 R:63961)
[13:52:10.630293] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63962 R:63962)
[13:52:10.646644] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63963 R:63963)
[13:52:10.647073] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63964 R:63964)
[13:52:10.663387] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63965 R:63965)
[13:52:10.663607] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63966 R:63966)
[13:52:10.679800] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63967 R:63967)
[13:52:10.680043] _buffer_get_frame:1002 [0x1f22970]: created zero frame (W:63968 R:63968)
[13:52:10.694047] sock_gets:116 gets EOF on closed socket
​

Und die dmesg:

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.15.74-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubunt                                                                               u1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1595 SMP Wed Oct 26 11:03:05 BST 2022
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
[    0.000000] random: crng init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x3a400000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003e5fffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003e5fffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003e5fffff]
[    0.000000] percpu: Embedded 16 pages/cpu s35724 r8192 d21620 u65536
[    0.000000] pcpu-alloc: s35724 r8192 d21620 u65536 alloc=16*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 253242
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2                                                                               835.enable_hdmi=1 bcm2708_fb.fbwidth=720 bcm2708_fb.fbheight=480 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_                                                                               mem.mem_base=0x3f000000 vc_mem.mem_size=0x3f600000  root=PARTUUID=8330941a-02 rootfstype=ext4 rootwait fsck.re                                                                               pair=yes net.ifnames=0 logo.nologo console=tty1
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 928824K/1021952K available (10240K kernel code, 1337K rwdata, 3076K rodata, 1024K init,                                                                                569K bss, 27592K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 33300 entries in 98 pages
[    0.000000] ftrace: allocated 98 pages with 3 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000]  Rude variant of Tasks RCU enabled.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440                                                                               795202767 ns
[    0.000000] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000011] Switching to timer-based delay loop, resolution 52ns
[    0.000213] Console: colour dummy device 80x30
[    0.000571] printk: console [tty1] enabled
[    0.000609] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=                                                                               192000)
[    0.000636] pid_max: default: 32768 minimum: 301
[    0.000716] LSM: Security Framework initializing
[    0.000850] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.000874] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001641] cgroup: Disabling memory control group subsystem
[    0.001795] CPU: Testing write buffer coherency: ok
[    0.002090] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.002777] Setting up static identity map for 0x100000 - 0x10003c
[    0.002871] rcu: Hierarchical SRCU implementation.
[    0.003345] smp: Bringing up secondary CPUs ...
[    0.003938] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.004583] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.005150] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.005216] smp: Brought up 1 node, 4 CPUs
[    0.005264] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.005279] CPU: All CPU(s) started in HYP mode.
[    0.005291] CPU: Virtualization extensions available.
[    0.005796] devtmpfs: initialized
[    0.013927] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.014060] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 n                                                                               s
[    0.014092] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.015630] pinctrl core: initialized pinctrl subsystem
[    0.016347] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.018619] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.021167] audit: initializing netlink subsys (disabled)
[    0.021332] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1
[    0.021644] thermal_sys: Registered thermal governor 'step_wise'
[    0.022123] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.022152] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.022303] Serial: AMBA PL011 UART driver
[    0.025632] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.040014] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-10-26T11:10:53, variant start                                                                               _cd
[    0.050021] raspberrypi-firmware soc:firmware: Firmware hash is c72ad6b26ff40c91ef776b847436094ee63fabee
[    0.076914] Kprobes globally optimized
[    0.079475] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
[    0.080560] SCSI subsystem initialized
[    0.080692] usbcore: registered new interface driver usbfs
[    0.080738] usbcore: registered new interface driver hub
[    0.080785] usbcore: registered new device driver usb
[    0.080938] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.081045] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    0.081178] pps_core: LinuxPPS API ver. 1 registered
[    0.081192] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.081218] PTP clock support registered
[    0.082234] clocksource: Switched to clocksource arch_sys_counter
[    0.112644] VFS: Disk quotas dquot_6.6.0
[    0.112725] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.112830] FS-Cache: Loaded
[    0.112973] CacheFiles: Loaded
[    0.117237] NET: Registered PF_INET protocol family
[    0.117400] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.118203] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    0.118240] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.118263] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.118327] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.118420] TCP: Hash tables configured (established 8192 bind 8192)
[    0.118523] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.118559] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.118703] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.119283] RPC: Registered named UNIX socket transport module.
[    0.119300] RPC: Registered udp transport module.
[    0.119313] RPC: Registered tcp transport module.
[    0.119325] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.832278] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    0.834144] Initialise system trusted keyrings
[    0.834337] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    0.838723] zbud: loaded
[    0.839692] FS-Cache: Netfs 'nfs' registered for caching
[    0.840140] NFS: Registering the id_resolver key type
[    0.840180] Key type id_resolver registered
[    0.840193] Key type id_legacy registered
[    0.840265] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.840282] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    0.840918] Key type asymmetric registered
[    0.840934] Asymmetric key parser 'x509' registered
[    0.840999] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.841018] io scheduler mq-deadline registered
[    0.841032] io scheduler kyber registered
[    0.843198] bcm2708_fb soc:fb: FB found 1 display(s)
[    0.848130] Console: switching to colour frame buffer device 90x30
[    0.850242] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 720x480
[    0.853634] bcm2835-rng 3f104000.rng: hwrng registered
[    0.854615] vc-mem: phys_addr:0x00000000 mem_base=0x3f000000 mem_size:0x3f600000(1014 MiB)
[    0.856681] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    0.862992] brd: module loaded
[    0.868292] loop: module loaded
[    0.869449] Loading iSCSI transport class v2.0-870.
[    0.871194] usbcore: registered new interface driver lan78xx
[    0.872065] usbcore: registered new interface driver smsc95xx
[    0.872906] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.601548] Core Release: 2.80a
[    1.602363] Setting default values for core params
[    1.603170] Finished setting default values for core params
[    1.804129] Using Buffer DMA mode
[    1.804917] Periodic Transfer Interrupt Enhancement - disabled
[    1.805717] Multiprocessor Interrupt Enhancement - disabled
[    1.806519] OTG VER PARAM: 0, OTG VER FLAG: 0
[    1.807342] Dedicated Tx FIFOs mode

[    1.808546] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ba514000 dma = 0xfa514000 len=9024
[    1.810909] FIQ FSM acceleration enabled for :
               Non-periodic Split Transactions
               Periodic Split Transactions
               High-Speed Isochronous Endpoints
               Interrupt/Control Split Transaction hack enabled
[    1.814786] dwc_otg: Microframe scheduler enabled

[    1.814847] WARN::hcd_init_fiq:457: FIQ on core 1

[    1.816375] WARN::hcd_init_fiq:458: FIQ ASM at 807fa458 length 36

[    1.817871] WARN::hcd_init_fiq:497: MPHI regs_base at be810000
[    1.819330] dwc_otg 3f980000.usb: DWC OTG Controller
[    1.820064] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    1.820804] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
[    1.821562] Init: Port Power? op_state=1
[    1.822287] Init: Power Port (0)
[    1.823155] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.824635] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.825413] usb usb1: Product: DWC OTG Controller
[    1.826184] usb usb1: Manufacturer: Linux 5.15.74-v7+ dwc_otg_hcd
[    1.826961] usb usb1: SerialNumber: 3f980000.usb
[    1.828095] hub 1-0:1.0: USB hub found
[    1.828875] hub 1-0:1.0: 1 port detected
[    1.830014] dwc_otg: FIQ enabled
[    1.830020] dwc_otg: NAK holdoff enabled
[    1.830026] dwc_otg: FIQ split-transaction FSM enabled
[    1.830034] Module dwc_common_port init
[    1.830212] usbcore: registered new interface driver usb-storage
[    1.831090] mousedev: PS/2 mouse device common for all mice
[    1.833463] sdhci: Secure Digital Host Controller Interface driver
[    1.834245] sdhci: Copyright(c) Pierre Ossman
[    1.835167] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.837529] ledtrig-cpu: registered to indicate activity on CPUs
[    1.838429] hid: raw HID events driver (C) Jiri Kosina
[    1.839254] usbcore: registered new interface driver usbhid
[    1.840019] usbhid: USB HID core driver
[    1.843459] Initializing XFRM netlink socket
[    1.844265] NET: Registered PF_PACKET protocol family
[    1.845117] Key type dns_resolver registered
[    1.846190] Registering SWP/SWPB emulation handler
[    1.847286] registered taskstats version 1
[    1.848048] Loading compiled-in X.509 certificates
[    1.849203] Key type ._fscrypt registered
[    1.849943] Key type .fscrypt registered
[    1.850666] Key type fscrypt-provisioning registered
[    1.856843] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    1.857697] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
[    1.860460] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.861439] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.863128] sdhost: log_buf @ e9913743 (fa513000)
[    1.912920] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    1.934928] of_cfs_init
[    1.935809] of_cfs_init: OK
[    1.937129] Waiting for root device PARTUUID=8330941a-02...
[    1.952337] Indeed it is in host mode hprt0 = 00021501
[    1.999945] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.004559] mmc0: new high speed SDHC card at address aaaa
[    2.012597] mmcblk0: mmc0:aaaa SC16G 14.8 GiB
[    2.018623]  mmcblk0: p1 p2
[    2.019709] mmcblk0: mmc0:aaaa SC16G 14.8 GiB
[    2.033735] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    2.035356] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    2.043523] devtmpfs: mounted
[    2.048043] Freeing unused kernel image (initmem) memory: 1024K
[    2.062468] Run /sbin/init as init process
[    2.063281]   with arguments:
[    2.063286]     /sbin/init
[    2.063291]   with environment:
[    2.063296]     HOME=/
[    2.063301]     TERM=linux
[    2.162262] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    2.163219] Indeed it is in host mode hprt0 = 00001101
[    2.402423] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    2.404174] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.405520] hub 1-1:1.0: USB hub found
[    2.406483] hub 1-1:1.0: 4 ports detected
[    2.537099] systemd[1]: System time before build time, advancing clock.
[    2.707245] NET: Registered PF_INET6 protocol family
[    2.708997] Segment Routing with IPv6
[    2.709896] In-situ OAM (IOAM) with IPv6
[    2.722266] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    2.760966] systemd[1]: systemd 247.3-7+rpi1+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +AP                                                                               PARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KM                                                                               OD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[    2.770316] systemd[1]: Detected architecture arm.
[    2.797188] systemd[1]: Set hostname to <DietPi>.
[    2.852601] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    2.854756] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.856363] hub 1-1.1:1.0: USB hub found
[    2.857482] hub 1-1.1:1.0: 3 ports detected
[    2.952271] usb 1-1.3: new full-speed USB device number 4 using dwc_otg
[    3.103519] usb 1-1.3: New USB device found, idVendor=0d8c, idProduct=0102, bcdDevice= 0.10
[    3.105358] usb 1-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[    3.106301] usb 1-1.3: Product: USB Sound Device
[    3.110508] input: USB Sound Device         as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.3/                                                                               0003:0D8C:0102.0001/input/input0
[    3.182532] hid-generic 0003:0D8C:0102.0001: input,hidraw0: USB HID v1.00 Device [USB Sound Device        ]                                                                                on usb-3f980000.usb-1.3/input3
[    3.381168] systemd[1]: Queued start job for default target Graphical Interface.
[    3.386502] systemd[1]: Created slice system-getty.slice.
[    3.389561] systemd[1]: Created slice system-modprobe.slice.
[    3.392629] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    3.395185] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    3.397623] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    3.400406] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    3.404074] systemd[1]: Reached target Local Encrypted Volumes.
[    3.406694] systemd[1]: Reached target Paths.
[    3.409018] systemd[1]: Reached target Remote File Systems.
[    3.411325] systemd[1]: Reached target Slices.
[    3.414662] systemd[1]: Listening on fsck to fsckd communication Socket.
[    3.417203] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    3.420130] systemd[1]: Listening on Journal Audit Socket.
[    3.422868] systemd[1]: Listening on Journal Socket (/dev/log).
[    3.425506] systemd[1]: Listening on Journal Socket.
[    3.431057] systemd[1]: Listening on udev Control Socket.
[    3.433855] systemd[1]: Listening on udev Kernel Socket.
[    3.436574] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    3.440795] systemd[1]: Mounting POSIX Message Queue File System...
[    3.446177] systemd[1]: Mounting Kernel Debug File System...
[    3.451834] systemd[1]: Mounting Kernel Trace File System...
[    3.458567] systemd[1]: Starting Restore / save the current clock...
[    3.464031] systemd[1]: Starting Set the console keyboard layout...
[    3.469394] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    3.476397] systemd[1]: Starting Load Kernel Module configfs...
[    3.482609] systemd[1]: Starting Load Kernel Module fuse...
[    3.489562] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    3.495276] systemd[1]: Starting File System Check on Root Device...
[    3.504504] systemd[1]: Starting Journal Service...
[    3.521556] systemd[1]: Starting Load Kernel Modules...
[    3.528160] systemd[1]: Starting Coldplug All udev Devices...
[    3.540527] fuse: init (API version 7.34)
[    3.545812] systemd[1]: Mounted POSIX Message Queue File System.
[    3.553605] systemd[1]: Mounted Kernel Debug File System.
[    3.556267] systemd[1]: Mounted Kernel Trace File System.
[    3.560896] systemd[1]: Finished Restore / save the current clock.
[    3.565286] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    3.569989] systemd[1]: modprobe@configfs.service: Succeeded.
[    3.572526] systemd[1]: Finished Load Kernel Module configfs.
[    3.582020] systemd[1]: modprobe@fuse.service: Succeeded.
[    3.585726] systemd[1]: Finished Load Kernel Module fuse.
[    3.591507] systemd[1]: Finished Load Kernel Modules.
[    3.597962] systemd[1]: Mounting FUSE Control File System...
[    3.604425] systemd[1]: Mounting Kernel Configuration File System...
[    3.610658] systemd[1]: Started File System Check Daemon to report status.
[    3.617227] systemd[1]: Starting Apply Kernel Variables...
[    3.623888] systemd[1]: Mounted FUSE Control File System.
[    3.627102] systemd[1]: Mounted Kernel Configuration File System.
[    3.662423] systemd[1]: Started Journal Service.
[    3.665807] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
[    3.793156] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
[    3.794983] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.883139] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none.
[    3.919411] Adding 1101820k swap on /var/swap.  Priority:-2 extents:5 across:1486844k SSFS
[    4.059926] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
[    4.075004] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
[    4.080393] systemd-journald[123]: Received client request to flush runtime journal.
[    4.524658] mc: Linux media interface: v0.10
[    4.619143] usb 1-1.3: current rate 30464 is different from the runtime rate 96000
[    4.634896] usb 1-1.3: Warning! Unlikely big volume range (=8065), cval->res is probably wrong.
[    4.636659] usb 1-1.3: [9] FU [Mic Playback Volume] ch = 2, val = -6144/1921/1
[    4.640644] usb 1-1.3: Warning! Unlikely big volume range (=8065), cval->res is probably wrong.
[    4.642526] usb 1-1.3: [11] FU [Line Playback Volume] ch = 2, val = -6144/1921/1
[    4.651271] usb 1-1.3: Warning! Unlikely big volume range (=6928), cval->res is probably wrong.
[    4.653210] usb 1-1.3: [8] FU [Mic Capture Volume] ch = 2, val = -4096/2832/1
[    4.657266] usb 1-1.3: Warning! Unlikely big volume range (=6928), cval->res is probably wrong.
[    4.659236] usb 1-1.3: [15] FU [Line Capture Volume] ch = 2, val = -4096/2832/1
[    4.663264] usb 1-1.3: Warning! Unlikely big volume range (=6928), cval->res is probably wrong.
[    4.665369] usb 1-1.3: [2] FU [PCM Capture Volume] ch = 2, val = -4096/2832/1
[    4.667161] usbcore: registered new interface driver snd-usb-audio
[    7.064188] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   16.860822] export_store: invalid GPIO 158
[   17.165873] export_store: invalid GPIO 73
[   17.373459] export_store: invalid GPIO 149
[   17.614264] export_store: invalid GPIO 75
[   32.472318] cam-dummy-reg: disabling
​[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 5.15.74-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubunt                                                                               u1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1595 SMP Wed Oct 26 11:03:05 BST 2022
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
[    0.000000] random: crng init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x3a400000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003e5fffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003e5fffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003e5fffff]
[    0.000000] percpu: Embedded 16 pages/cpu s35724 r8192 d21620 u65536
[    0.000000] pcpu-alloc: s35724 r8192 d21620 u65536 alloc=16*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 253242
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2                                                                               835.enable_hdmi=1 bcm2708_fb.fbwidth=720 bcm2708_fb.fbheight=480 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_                                                                               mem.mem_base=0x3f000000 vc_mem.mem_size=0x3f600000  root=PARTUUID=8330941a-02 rootfstype=ext4 rootwait fsck.re                                                                               pair=yes net.ifnames=0 logo.nologo console=tty1
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 928824K/1021952K available (10240K kernel code, 1337K rwdata, 3076K rodata, 1024K init,                                                                                569K bss, 27592K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 33300 entries in 98 pages
[    0.000000] ftrace: allocated 98 pages with 3 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Hierarchical RCU implementation.
[    0.000000]  Rude variant of Tasks RCU enabled.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440                                                                               795202767 ns
[    0.000000] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000011] Switching to timer-based delay loop, resolution 52ns
[    0.000213] Console: colour dummy device 80x30
[    0.000571] printk: console [tty1] enabled
[    0.000609] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=                                                                               192000)
[    0.000636] pid_max: default: 32768 minimum: 301
[    0.000716] LSM: Security Framework initializing
[    0.000850] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.000874] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001641] cgroup: Disabling memory control group subsystem
[    0.001795] CPU: Testing write buffer coherency: ok
[    0.002090] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.002777] Setting up static identity map for 0x100000 - 0x10003c
[    0.002871] rcu: Hierarchical SRCU implementation.
[    0.003345] smp: Bringing up secondary CPUs ...
[    0.003938] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.004583] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.005150] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.005216] smp: Brought up 1 node, 4 CPUs
[    0.005264] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.005279] CPU: All CPU(s) started in HYP mode.
[    0.005291] CPU: Virtualization extensions available.
[    0.005796] devtmpfs: initialized
[    0.013927] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.014060] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 n                                                                               s
[    0.014092] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.015630] pinctrl core: initialized pinctrl subsystem
[    0.016347] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.018619] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.021167] audit: initializing netlink subsys (disabled)
[    0.021332] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1
[    0.021644] thermal_sys: Registered thermal governor 'step_wise'
[    0.022123] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.022152] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.022303] Serial: AMBA PL011 UART driver
[    0.025632] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.040014] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-10-26T11:10:53, variant start                                                                               _cd
[    0.050021] raspberrypi-firmware soc:firmware: Firmware hash is c72ad6b26ff40c91ef776b847436094ee63fabee
[    0.076914] Kprobes globally optimized
[    0.079475] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
[    0.080560] SCSI subsystem initialized
[    0.080692] usbcore: registered new interface driver usbfs
[    0.080738] usbcore: registered new interface driver hub
[    0.080785] usbcore: registered new device driver usb
[    0.080938] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.081045] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    0.081178] pps_core: LinuxPPS API ver. 1 registered
[    0.081192] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.081218] PTP clock support registered
[    0.082234] clocksource: Switched to clocksource arch_sys_counter
[    0.112644] VFS: Disk quotas dquot_6.6.0
[    0.112725] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.112830] FS-Cache: Loaded
[    0.112973] CacheFiles: Loaded
[    0.117237] NET: Registered PF_INET protocol family
[    0.117400] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.118203] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    0.118240] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.118263] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.118327] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.118420] TCP: Hash tables configured (established 8192 bind 8192)
[    0.118523] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.118559] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.118703] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.119283] RPC: Registered named UNIX socket transport module.
[    0.119300] RPC: Registered udp transport module.
[    0.119313] RPC: Registered tcp transport module.
[    0.119325] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.832278] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    0.834144] Initialise system trusted keyrings
[    0.834337] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    0.838723] zbud: loaded
[    0.839692] FS-Cache: Netfs 'nfs' registered for caching
[    0.840140] NFS: Registering the id_resolver key type
[    0.840180] Key type id_resolver registered
[    0.840193] Key type id_legacy registered
[    0.840265] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.840282] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    0.840918] Key type asymmetric registered
[    0.840934] Asymmetric key parser 'x509' registered
[    0.840999] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    0.841018] io scheduler mq-deadline registered
[    0.841032] io scheduler kyber registered
[    0.843198] bcm2708_fb soc:fb: FB found 1 display(s)
[    0.848130] Console: switching to colour frame buffer device 90x30
[    0.850242] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 720x480
[    0.853634] bcm2835-rng 3f104000.rng: hwrng registered
[    0.854615] vc-mem: phys_addr:0x00000000 mem_base=0x3f000000 mem_size:0x3f600000(1014 MiB)
[    0.856681] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    0.862992] brd: module loaded
[    0.868292] loop: module loaded
[    0.869449] Loading iSCSI transport class v2.0-870.
[    0.871194] usbcore: registered new interface driver lan78xx
[    0.872065] usbcore: registered new interface driver smsc95xx
[    0.872906] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.601548] Core Release: 2.80a
[    1.602363] Setting default values for core params
[    1.603170] Finished setting default values for core params
[    1.804129] Using Buffer DMA mode
[    1.804917] Periodic Transfer Interrupt Enhancement - disabled
[    1.805717] Multiprocessor Interrupt Enhancement - disabled
[    1.806519] OTG VER PARAM: 0, OTG VER FLAG: 0
[    1.807342] Dedicated Tx FIFOs mode

[    1.808546] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ba514000 dma = 0xfa514000 len=9024
[    1.810909] FIQ FSM acceleration enabled for :
               Non-periodic Split Transactions
               Periodic Split Transactions
               High-Speed Isochronous Endpoints
               Interrupt/Control Split Transaction hack enabled
[    1.814786] dwc_otg: Microframe scheduler enabled

[    1.814847] WARN::hcd_init_fiq:457: FIQ on core 1

[    1.816375] WARN::hcd_init_fiq:458: FIQ ASM at 807fa458 length 36

[    1.817871] WARN::hcd_init_fiq:497: MPHI regs_base at be810000
[    1.819330] dwc_otg 3f980000.usb: DWC OTG Controller
[    1.820064] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    1.820804] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
[    1.821562] Init: Port Power? op_state=1
[    1.822287] Init: Power Port (0)
[    1.823155] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.824635] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.825413] usb usb1: Product: DWC OTG Controller
[    1.826184] usb usb1: Manufacturer: Linux 5.15.74-v7+ dwc_otg_hcd
[    1.826961] usb usb1: SerialNumber: 3f980000.usb
[    1.828095] hub 1-0:1.0: USB hub found
[    1.828875] hub 1-0:1.0: 1 port detected
[    1.830014] dwc_otg: FIQ enabled
[    1.830020] dwc_otg: NAK holdoff enabled
[    1.830026] dwc_otg: FIQ split-transaction FSM enabled
[    1.830034] Module dwc_common_port init
[    1.830212] usbcore: registered new interface driver usb-storage
[    1.831090] mousedev: PS/2 mouse device common for all mice
[    1.833463] sdhci: Secure Digital Host Controller Interface driver
[    1.834245] sdhci: Copyright(c) Pierre Ossman
[    1.835167] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.837529] ledtrig-cpu: registered to indicate activity on CPUs
[    1.838429] hid: raw HID events driver (C) Jiri Kosina
[    1.839254] usbcore: registered new interface driver usbhid
[    1.840019] usbhid: USB HID core driver
[    1.843459] Initializing XFRM netlink socket
[    1.844265] NET: Registered PF_PACKET protocol family
[    1.845117] Key type dns_resolver registered
[    1.846190] Registering SWP/SWPB emulation handler
[    1.847286] registered taskstats version 1
[    1.848048] Loading compiled-in X.509 certificates
[    1.849203] Key type ._fscrypt registered
[    1.849943] Key type .fscrypt registered
[    1.850666] Key type fscrypt-provisioning registered
[    1.856843] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    1.857697] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
[    1.860460] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.861439] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.863128] sdhost: log_buf @ e9913743 (fa513000)
[    1.912920] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    1.934928] of_cfs_init
[    1.935809] of_cfs_init: OK
[    1.937129] Waiting for root device PARTUUID=8330941a-02...
[    1.952337] Indeed it is in host mode hprt0 = 00021501
[    1.999945] mmc0: host does not support reading read-only switch, assuming write-enable
[    2.004559] mmc0: new high speed SDHC card at address aaaa
[    2.012597] mmcblk0: mmc0:aaaa SC16G 14.8 GiB
[    2.018623]  mmcblk0: p1 p2
[    2.019709] mmcblk0: mmc0:aaaa SC16G 14.8 GiB
[    2.033735] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    2.035356] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    2.043523] devtmpfs: mounted
[    2.048043] Freeing unused kernel image (initmem) memory: 1024K
[    2.062468] Run /sbin/init as init process
[    2.063281]   with arguments:
[    2.063286]     /sbin/init
[    2.063291]   with environment:
[    2.063296]     HOME=/
[    2.063301]     TERM=linux
[    2.162262] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    2.163219] Indeed it is in host mode hprt0 = 00001101
[    2.402423] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    2.404174] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.405520] hub 1-1:1.0: USB hub found
[    2.406483] hub 1-1:1.0: 4 ports detected
[    2.537099] systemd[1]: System time before build time, advancing clock.
[    2.707245] NET: Registered PF_INET6 protocol family
[    2.708997] Segment Routing with IPv6
[    2.709896] In-situ OAM (IOAM) with IPv6
[    2.722266] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    2.760966] systemd[1]: systemd 247.3-7+rpi1+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +AP                                                                               PARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KM                                                                               OD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[    2.770316] systemd[1]: Detected architecture arm.
[    2.797188] systemd[1]: Set hostname to <DietPi>.
[    2.852601] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
[    2.854756] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.856363] hub 1-1.1:1.0: USB hub found
[    2.857482] hub 1-1.1:1.0: 3 ports detected
[    2.952271] usb 1-1.3: new full-speed USB device number 4 using dwc_otg
[    3.103519] usb 1-1.3: New USB device found, idVendor=0d8c, idProduct=0102, bcdDevice= 0.10
[    3.105358] usb 1-1.3: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[    3.106301] usb 1-1.3: Product: USB Sound Device
[    3.110508] input: USB Sound Device         as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.3/                                                                               0003:0D8C:0102.0001/input/input0
[    3.182532] hid-generic 0003:0D8C:0102.0001: input,hidraw0: USB HID v1.00 Device [USB Sound Device        ]                                                                                on usb-3f980000.usb-1.3/input3
[    3.381168] systemd[1]: Queued start job for default target Graphical Interface.
[    3.386502] systemd[1]: Created slice system-getty.slice.
[    3.389561] systemd[1]: Created slice system-modprobe.slice.
[    3.392629] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    3.395185] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    3.397623] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    3.400406] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    3.404074] systemd[1]: Reached target Local Encrypted Volumes.
[    3.406694] systemd[1]: Reached target Paths.
[    3.409018] systemd[1]: Reached target Remote File Systems.
[    3.411325] systemd[1]: Reached target Slices.
[    3.414662] systemd[1]: Listening on fsck to fsckd communication Socket.
[    3.417203] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    3.420130] systemd[1]: Listening on Journal Audit Socket.
[    3.422868] systemd[1]: Listening on Journal Socket (/dev/log).
[    3.425506] systemd[1]: Listening on Journal Socket.
[    3.431057] systemd[1]: Listening on udev Control Socket.
[    3.433855] systemd[1]: Listening on udev Kernel Socket.
[    3.436574] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    3.440795] systemd[1]: Mounting POSIX Message Queue File System...
[    3.446177] systemd[1]: Mounting Kernel Debug File System...
[    3.451834] systemd[1]: Mounting Kernel Trace File System...
[    3.458567] systemd[1]: Starting Restore / save the current clock...
[    3.464031] systemd[1]: Starting Set the console keyboard layout...
[    3.469394] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    3.476397] systemd[1]: Starting Load Kernel Module configfs...
[    3.482609] systemd[1]: Starting Load Kernel Module fuse...
[    3.489562] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    3.495276] systemd[1]: Starting File System Check on Root Device...
[    3.504504] systemd[1]: Starting Journal Service...
[    3.521556] systemd[1]: Starting Load Kernel Modules...
[    3.528160] systemd[1]: Starting Coldplug All udev Devices...
[    3.540527] fuse: init (API version 7.34)
[    3.545812] systemd[1]: Mounted POSIX Message Queue File System.
[    3.553605] systemd[1]: Mounted Kernel Debug File System.
[    3.556267] systemd[1]: Mounted Kernel Trace File System.
[    3.560896] systemd[1]: Finished Restore / save the current clock.
[    3.565286] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    3.569989] systemd[1]: modprobe@configfs.service: Succeeded.
[    3.572526] systemd[1]: Finished Load Kernel Module configfs.
[    3.582020] systemd[1]: modprobe@fuse.service: Succeeded.
[    3.585726] systemd[1]: Finished Load Kernel Module fuse.
[    3.591507] systemd[1]: Finished Load Kernel Modules.
[    3.597962] systemd[1]: Mounting FUSE Control File System...
[    3.604425] systemd[1]: Mounting Kernel Configuration File System...
[    3.610658] systemd[1]: Started File System Check Daemon to report status.
[    3.617227] systemd[1]: Starting Apply Kernel Variables...
[    3.623888] systemd[1]: Mounted FUSE Control File System.
[    3.627102] systemd[1]: Mounted Kernel Configuration File System.
[    3.662423] systemd[1]: Started Journal Service.
[    3.665807] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
[    3.793156] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
[    3.794983] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.883139] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null). Quota mode: none.
[    3.919411] Adding 1101820k swap on /var/swap.  Priority:-2 extents:5 across:1486844k SSFS
[    4.059926] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
[    4.075004] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
[    4.080393] systemd-journald[123]: Received client request to flush runtime journal.
[    4.524658] mc: Linux media interface: v0.10
[    4.619143] usb 1-1.3: current rate 30464 is different from the runtime rate 96000
[    4.634896] usb 1-1.3: Warning! Unlikely big volume range (=8065), cval->res is probably wrong.
[    4.636659] usb 1-1.3: [9] FU [Mic Playback Volume] ch = 2, val = -6144/1921/1
[    4.640644] usb 1-1.3: Warning! Unlikely big volume range (=8065), cval->res is probably wrong.
[    4.642526] usb 1-1.3: [11] FU [Line Playback Volume] ch = 2, val = -6144/1921/1
[    4.651271] usb 1-1.3: Warning! Unlikely big volume range (=6928), cval->res is probably wrong.
[    4.653210] usb 1-1.3: [8] FU [Mic Capture Volume] ch = 2, val = -4096/2832/1
[    4.657266] usb 1-1.3: Warning! Unlikely big volume range (=6928), cval->res is probably wrong.
[    4.659236] usb 1-1.3: [15] FU [Line Capture Volume] ch = 2, val = -4096/2832/1
[    4.663264] usb 1-1.3: Warning! Unlikely big volume range (=6928), cval->res is probably wrong.
[    4.665369] usb 1-1.3: [2] FU [PCM Capture Volume] ch = 2, val = -4096/2832/1
[    4.667161] usbcore: registered new interface driver snd-usb-audio
[    7.064188] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   16.860822] export_store: invalid GPIO 158
[   17.165873] export_store: invalid GPIO 73
[   17.373459] export_store: invalid GPIO 149
[   17.614264] export_store: invalid GPIO 75
[   32.472318] cam-dummy-reg: disabling
​
​

Kannst du damit was anfangen oder soll ich noch was anderes mitschneiden?

[matschie]

So ich glaube ich hab das Problem gelöst.

Ich habe zwei Änderungen in der Config des Routers vorgenommen:
DHCP Leasezeit erhöht (stand unnötig niedrig auf 1000s)
IGMP Snooping deaktiviert

Jetzt scheint es nach ersten Tests zu funktionieren.

[orli]

[ 4.619143] usb 1-1.3: current rate 30464 is different from the runtime rate 96000
[ 4.634896] usb 1-1.3: Warning! Unlikely big volume range (=8065), cval->res is probably wrong.
[ 4.636659] usb 1-1.3: [9] FU [Mic Playback Volume] ch = 2, val = -6144/1921/1
[ 4.640644] usb 1-1.3: Warning! Unlikely big volume range (=8065), cval->res is probably wrong.
[ 4.642526] usb 1-1.3: [11] FU [Line Playback Volume] ch = 2, val = -6144/1921/1
[ 4.651271] usb 1-1.3: Warning! Unlikely big volume range (=6928), cval->res is probably wrong.
[ 4.653210] usb 1-1.3: [8] FU [Mic Capture Volume] ch = 2, val = -4096/2832/1
[ 4.657266] usb 1-1.3: Warning! Unlikely big volume range (=6928), cval->res is probably wrong.
[ 4.659236] usb 1-1.3: [15] FU [Line Capture Volume] ch = 2, val = -4096/2832/1
[ 4.663264] usb 1-1.3: Warning! Unlikely big volume range (=6928), cval->res is probably wrong.
[ 4.665369] usb 1-1.3: [2] FU [PCM Capture Volume] ch = 2, val = -4096/2832/1

Das sieht aber auch nicht so aus, als wenn das so soll, oder?

[matschie]

Jo, stimmt. Macht aber nix, da ich die Soundkarte sowieso nochmal wechseln werde, da die die ich habe leider zu Störgeräuschen zwischen den Zonen führt. Da ich auch noch nicht für jede Zone Verstärker hab steige ich wohl auf ein KAB9 um. Danach befasse ich mich mit Treiberproblemen.