Reply To: Wiedergabe hängt regelmäßig

Max2Play Home 2016 (en) Forums Max2Play Add-ons Wiedergabe hängt regelmäßig Reply To: Wiedergabe hängt regelmäßig

12. Mai 2021 at 8:08 #51236

Guten Morgen,

also habe grade die Wiedergabe nach ein paar Tagen der abstinenz gestartet.
Die Wiedergabe funktioniert wie folgt grade, egal wie oft ich das Lied neustarte oder mein iPhone neu mit einem der Player verbinde:

2Sek Musik, 0,5Sek Krzzzz/Wiederholungsschleife, 0,5 Sek Musik, 0,5 Sek Krzzz/Wiederholungsschleife, 2 Sek Musik, usw -> in Endlosschleife, die Zeitangaben variieren, aber sind jedoch immer unter 5 Sekunden.
Raspi 4 ist via. LAN direkt an die Fritzbox angeschlossen. DSL Telekom 250er Leitung – ohne Probleme. Als Netzteil habe ich ein Netzteil HDR-100-24N von Meanwell -> 100W also.

Das Problem habe ich seit über einem Jahr und trotz 3x neue Images draufgezogen. Zeitweilig hatte ich das Problem nicht mehr, als ich (Multi)Shairport genutzt habe.
Das Problem tritt also nur bei Squezlite + LMS auf.

[21-05-12 08:01:54.7202] Plugins::ShairTunes2W::Plugin::handleSocketConnect (477) New connection from: 192.168.20.28
[21-05-12 08:01:54.7217] Plugins::ShairTunes2W::Plugin::createListenPort (398) Created listener on port 43437
[21-05-12 08:01:54.7256] Plugins::ShairTunes2W::Plugin::conn_handle_request (759) DACP-ID: 79D1C18B7F80C2D8, Active Remote: 1459602536
[21-05-12 08:01:54.7343] Plugins::ShairTunes2W::Plugin::conn_handle_request (759) DACP-ID: 79D1C18B7F80C2D8, Active Remote: 1459602536
[21-05-12 08:01:54.7555] Plugins::ShairTunes2W::Plugin::conn_handle_request (759) DACP-ID: 79D1C18B7F80C2D8, Active Remote: 1459602536
[21-05-12 08:01:54.8594] Plugins::ShairTunes2W::Plugin::conn_handle_request (832) decode command: $VAR1 = 'host';
$VAR2 = '192.168.20.28';
$VAR3 = 'socket';
$VAR4 = 33281;
$VAR5 = 'fmtp';
$VAR6 = '96 352 0 16 40 10 14 2 255 0 0 44100';
$VAR7 = 'cport';
$VAR8 = '54718';
$VAR9 = 'tport';
$VAR10 = '63892';
$VAR11 = 'latencies';
$VAR12 = '1000:2000:f';
$VAR13 = 'codec';
$VAR14 = 'mp3:320';
$VAR15 = 'iv';
$VAR16 = '81777bb981b11b1ac422c4fb50186290';
$VAR17 = 'key';
$VAR18 = 'fbca516b039c8abca816872a846288e8';
[21-05-12 08:01:54.8983] Plugins::ShairTunes2W::Plugin::conn_handle_request (850) Helper output: shairport_helper: VERSION: 0.203.4
[21-05-12 08:01:54.8995] Plugins::ShairTunes2W::Plugin::conn_handle_request (861) launched decoder: Proc::Background=HASH(0x5a4fa70) on ports: 50992/57201/38013/59357, http port: 43437
[21-05-12 08:01:54.9090] Plugins::ShairTunes2W::Plugin::conn_handle_request (898) Playing url: airplay://192.168.20.251:59357/17219_stream.mp3
[21-05-12 08:01:54.9431] Plugins::ShairTunes2W::Plugin::conn_handle_request (908) Record from 0, 0
[21-05-12 08:01:54.9785] Plugins::ShairTunes2W::Plugin::conn_handle_request (917) Flush up to 15125, 2592636167
[21-05-12 08:01:55.4393] Plugins::ShairTunes2W::Plugin::handleHelperOut (561) From helper: play
[21-05-12 08:01:57.0502] Plugins::ShairTunes2W::Plugin::conn_handle_request (759) DACP-ID: 79D1C18B7F80C2D8, Active Remote: 1459602536
[21-05-12 08:01:57.0597] Plugins::ShairTunes2W::Plugin::mDNSlistener (1084) Found remote: 79D1C18B7F80C2D8, 192.168.20.28:51570
[21-05-12 08:01:57.6905] Plugins::ShairTunes2W::Plugin::handleCoverConnect (508) New cover proxy connection from 192.168.20.200
[21-05-12 08:01:57.6936] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: GET /05d387666da54dbb8b8f88316a09816a/cover_192x192_p.jpeg HTTP/1.1
[21-05-12 08:01:57.6944] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Host: 192.168.20.251:43437
[21-05-12 08:01:57.6957] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
[21-05-12 08:01:57.6965] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Accept: image/webp,*/*
[21-05-12 08:01:57.6973] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Accept-Language: de,en-US;q=0.7,en;q=0.3
[21-05-12 08:01:57.6981] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Accept-Encoding: gzip, deflate
[21-05-12 08:01:57.6987] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: DNT: 1
[21-05-12 08:01:57.6994] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Connection: keep-alive
[21-05-12 08:01:57.7002] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Referer: http://192.168.20.251:9000/
[21-05-12 08:01:57.7052] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Cookie: Squeezebox-expandPlayerControl=false; Squeezebox-expanded-MY_MUSIC=1; Squeezebox-expanded-FAVORITES=1; Squeezebox-expanded-PLUGINS=1; Squeezebox-expanded-RADIO=1; Squeezebox-player=b8%3A26%3Aeb%3Ac1%3A71%3Af0; Squeezebox-enableHiDPI=1.5; Squeezebox-playersettings=null; Squeezebox-advancedsettings=null; Squeezebox-expanded-updatePlugins=1; Squeezebox-expanded-activePlugins=1; Squeezebox-expanded-inactivePlugins=1; Squeezebox-expanded-otherPlugins0=1; Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=0; Squeezebox-albumView=
[21-05-12 08:01:57.7057] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: 
[21-05-12 08:01:57.7078] Plugins::ShairTunes2W::Plugin::handleCoverRequest (545) Coverart sent 49324 over 49324
[21-05-12 08:01:58.6554] Plugins::ShairTunes2W::Plugin::handleCoverConnect (508) New cover proxy connection from 192.168.20.200
[21-05-12 08:01:58.6562] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: GET /05d387666da54dbb8b8f88316a09816a/cover.jpeg HTTP/1.1
[21-05-12 08:01:58.6566] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Host: 192.168.20.251:43437
[21-05-12 08:01:58.6572] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:88.0) Gecko/20100101 Firefox/88.0
[21-05-12 08:01:58.6576] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Accept: image/webp,*/*
[21-05-12 08:01:58.6580] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Accept-Language: de,en-US;q=0.7,en;q=0.3
[21-05-12 08:01:58.6584] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Accept-Encoding: gzip, deflate
[21-05-12 08:01:58.6587] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: DNT: 1
[21-05-12 08:01:58.6590] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Connection: keep-alive
[21-05-12 08:01:58.6594] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Referer: http://192.168.20.251:9000/
[21-05-12 08:01:58.6619] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: Cookie: Squeezebox-expandPlayerControl=false; Squeezebox-expanded-MY_MUSIC=1; Squeezebox-expanded-FAVORITES=1; Squeezebox-expanded-PLUGINS=1; Squeezebox-expanded-RADIO=1; Squeezebox-player=b8%3A26%3Aeb%3Ac1%3A71%3Af0; Squeezebox-enableHiDPI=1.5; Squeezebox-playersettings=null; Squeezebox-advancedsettings=null; Squeezebox-expanded-updatePlugins=1; Squeezebox-expanded-activePlugins=1; Squeezebox-expanded-inactivePlugins=1; Squeezebox-expanded-otherPlugins0=1; Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=0; Squeezebox-albumView=
[21-05-12 08:01:58.6671] Plugins::ShairTunes2W::Plugin::handleCoverRequest (517) Image proxy request: 
[21-05-12 08:01:58.6683] Plugins::ShairTunes2W::Plugin::handleCoverRequest (545) Coverart sent 49324 over 49324

Zugleich noch ein Auszug aus dem Syslog:


May 12 08:00:14 Musikserver systemd[1]: Created slice User Slice of UID 1000.
May 12 08:00:14 Musikserver systemd[1]: Starting User Runtime Directory /run/user/1000...
May 12 08:00:14 Musikserver systemd[1]: Started User Runtime Directory /run/user/1000.
May 12 08:00:14 Musikserver systemd[1]: Starting User Manager for UID 1000...
May 12 08:00:14 Musikserver systemd[16792]: Listening on GnuPG cryptographic agent and passphrase cache.
May 12 08:00:14 Musikserver systemd[16792]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
May 12 08:00:14 Musikserver systemd[16792]: Listening on GnuPG network certificate management daemon.
May 12 08:00:14 Musikserver systemd[16792]: Listening on Sound System.
May 12 08:00:14 Musikserver systemd[16792]: Reached target Timers.
May 12 08:00:14 Musikserver systemd[16792]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
May 12 08:00:14 Musikserver systemd[16792]: Reached target Paths.
May 12 08:00:14 Musikserver systemd[16792]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
May 12 08:00:14 Musikserver systemd[16792]: Starting D-Bus User Message Bus Socket.
May 12 08:00:14 Musikserver systemd[16792]: Listening on D-Bus User Message Bus Socket.
May 12 08:00:14 Musikserver systemd[16792]: Reached target Sockets.
May 12 08:00:14 Musikserver systemd[16792]: Reached target Basic System.
May 12 08:00:14 Musikserver systemd[16792]: Reached target Default.
May 12 08:00:14 Musikserver systemd[16792]: Startup finished in 207ms.
May 12 08:00:14 Musikserver systemd[1]: Started User Manager for UID 1000.
May 12 08:00:14 Musikserver systemd[1]: Started Session c24 of user pi.
May 12 08:00:14 Musikserver systemd[16792]: Starting Sound Service...
May 12 08:00:15 Musikserver systemd[1]: session-c24.scope: Succeeded.
May 12 08:00:15 Musikserver dbus-daemon[365]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.81' (uid=1000 pid=16816 comm="/usr/bin/pulseaudio --daemonize=no ")
May 12 08:00:15 Musikserver systemd[1]: Starting RealtimeKit Scheduling Policy Service...
May 12 08:00:15 Musikserver dbus-daemon[365]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
May 12 08:00:15 Musikserver systemd[1]: Started RealtimeKit Scheduling Policy Service.
May 12 08:00:15 Musikserver rtkit-daemon[16825]: Successfully called chroot.
May 12 08:00:15 Musikserver rtkit-daemon[16825]: Successfully dropped privileges.
May 12 08:00:15 Musikserver rtkit-daemon[16825]: Successfully limited resources.
May 12 08:00:15 Musikserver rtkit-daemon[16825]: Running.
May 12 08:00:15 Musikserver rtkit-daemon[16825]: Watchdog thread running.
May 12 08:00:15 Musikserver rtkit-daemon[16825]: Canary thread running.
May 12 08:00:15 Musikserver systemd[16792]: Started D-Bus User Message Bus.
May 12 08:00:15 Musikserver systemd[16792]: Started Sound Service.
May 12 08:00:25 Musikserver systemd[1]: Stopping User Manager for UID 1000...
May 12 08:00:25 Musikserver systemd[16792]: Stopping Sound Service...
May 12 08:00:25 Musikserver systemd[16792]: Stopped target Default.
May 12 08:00:25 Musikserver systemd[16792]: Stopping D-Bus User Message Bus...
May 12 08:00:25 Musikserver systemd[16792]: dbus.service: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Stopped D-Bus User Message Bus.
May 12 08:00:25 Musikserver systemd[16792]: pulseaudio.service: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Stopped Sound Service.
May 12 08:00:25 Musikserver systemd[16792]: Stopped target Basic System.
May 12 08:00:25 Musikserver systemd[16792]: Stopped target Timers.
May 12 08:00:25 Musikserver systemd[16792]: Stopped target Sockets.
May 12 08:00:25 Musikserver systemd[16792]: gpg-agent.socket: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Closed GnuPG cryptographic agent and passphrase cache.
May 12 08:00:25 Musikserver systemd[16792]: gpg-agent-browser.socket: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
May 12 08:00:25 Musikserver systemd[16792]: gpg-agent-ssh.socket: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Closed GnuPG cryptographic agent (ssh-agent emulation).
May 12 08:00:25 Musikserver systemd[16792]: gpg-agent-extra.socket: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
May 12 08:00:25 Musikserver systemd[16792]: dirmngr.socket: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Closed GnuPG network certificate management daemon.
May 12 08:00:25 Musikserver systemd[16792]: dbus.socket: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Closed D-Bus User Message Bus Socket.
May 12 08:00:25 Musikserver systemd[16792]: Stopped target Paths.
May 12 08:00:25 Musikserver systemd[16792]: pulseaudio.socket: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Closed Sound System.
May 12 08:00:25 Musikserver systemd[16792]: Reached target Shutdown.
May 12 08:00:25 Musikserver systemd[16792]: systemd-exit.service: Succeeded.
May 12 08:00:25 Musikserver systemd[16792]: Started Exit the Session.
May 12 08:00:25 Musikserver systemd[16792]: Reached target Exit the Session.
May 12 08:00:25 Musikserver systemd[1]: [email protected]: Succeeded.
May 12 08:00:25 Musikserver systemd[1]: Stopped User Manager for UID 1000.
May 12 08:00:25 Musikserver systemd[1]: Stopping User Runtime Directory /run/user/1000...
May 12 08:00:25 Musikserver systemd[16062]: run-user-1000.mount: Succeeded.
May 12 08:00:25 Musikserver systemd[1]: run-user-1000.mount: Succeeded.
May 12 08:00:25 Musikserver systemd[1]: [email protected]: Succeeded.
May 12 08:00:25 Musikserver systemd[1]: Stopped User Runtime Directory /run/user/1000.
May 12 08:00:25 Musikserver systemd[1]: Removed slice User Slice of UID 1000.

Vielleicht verschafft das ganze ja einen Überblick.
Ich starte nun den PI neu und es wird danach wieder einen Tag oder zwei völlig normal funktionieren.