Modul für MPD

HGButte · 09 Oktober 2023, 19:56:21

Habe meinen Raspi von bullseye auf bookworm upgegradet.

Bei mpd selbst musste ich einige Änderungen machen. Ich nutze einen usb audio Stick und nicht das Original Raspi Audio. Nach dem Upgrade hat sich da einiges geändert. Mpd selbst läuft jetzt aber wieder.

Aktuell bringt aber das 73_MPD Modul meinen fhem Server komplett zum Absturz direkt nach dessen Start.

Code Auswählen

2023.10.09 18:49:47 1: Including fhem.cfg
2023.10.09 18:50:00 1: Including ./log/fhem.save
2023.10.09 18:50:09 0: Featurelevel: 6.2
2023.10.09 18:50:09 0: Server started with 261 defined entities (fhem.pl:28007/2023-09-28 perl:5.036000 os:linux user:fhem pid:18978)
2023.10.09 18:50:10 1: PERL WARNING: Use of uninitialized value $data in concatenation (.) or string at ./FHEM/73_MPD.pm line 1485, <FILE> line 1.
2023.10.09 18:50:11 1: PERL WARNING: timeout at ./FHEM/73_MPD.pm line 1033.
        main::__ANON__("ALRM") called at ./FHEM/73_MPD.pm line 1035
        eval {...} called at ./FHEM/73_MPD.pm line 1035
        eval {...} called at ./FHEM/73_MPD.pm line 1032
        main::mpd_cmd(HASH(0x3fea798), "i|outputs|x") called at ./FHEM/73_MPD.pm line 972
        main::MPD_Outputs_Status(HASH(0x3fea798)) called at ./FHEM/73_MPD.pm line 204
        main::MPD_updateConfig(HASH(0x3fea798)) called at fhem.pl line 3503
        main::HandleTimeout() called at fhem.pl line 705

Glaube das Problem ist erst nach einem "apt autoremove" aufgetreten. Bin mit aber nicht wirklich sicher.

Kann es an perl 5.36 liegen?

HGButte · 10 Oktober 2023, 21:50:55

Problem gelöst.

Long story short: Ich hatte ein Userreading, dass scheinbar bei der neuen mpd version von bookworm nicht mehr so wollte.

Medel · 01 Januar 2024, 23:56:19

Ich bekomme immer wieder einen socket error

Code Auswählen

2024.01.01 23:36:43 5: myMPD, idle PID 13494 found

2024.01.01 23:37:43 5: myMPD, idle PID 13494 found
2024.01.01 23:37:43 5: myMPD, MPD_EVENT : playlist
2024.01.01 23:37:43 4: myMPD, MPD_EVENT : playlist
2024.01.01 23:37:43 5: myMPD, new Playlist in -> 
2024.01.01 23:37:43 5: myMPD, IdleDone -> myMPD|socket error
2024.01.01 23:37:43 4: myMPD, idle error -> socket error

2024.01.01 23:38:43 4: myMPD, Idle new PID : 14065
2024.01.01 23:38:43 5: myMPD, mpd_cmd[1] -> command_list_begin
status
stats
currentsong
command_list_end
2024.01.01 23:38:43 5: myMPD, rec: volume: 100
2024.01.01 23:38:43 5: myMPD, rec: repeat: 0
2024.01.01 23:38:43 5: myMPD, rec: random: 0
2024.01.01 23:38:43 5: myMPD, rec: single: 0
2024.01.01 23:38:43 5: myMPD, rec: consume: 0
2024.01.01 23:38:43 5: myMPD, rec: playlist: 2
2024.01.01 23:38:43 5: myMPD, rec: playlistlength: 1
2024.01.01 23:38:43 5: myMPD, rec: xfade: 0
2024.01.01 23:38:43 5: myMPD, rec: state: play
2024.01.01 23:38:43 5: myMPD, rec: song: 0
2024.01.01 23:38:43 5: myMPD, rec: songid: 1
2024.01.01 23:38:43 5: myMPD, rec: time: 2109:0
2024.01.01 23:38:43 5: myMPD, rec: elapsed: 2109.267
2024.01.01 23:38:43 5: myMPD, rec: bitrate: 0
2024.01.01 23:38:43 5: myMPD, rec: artists: 0
2024.01.01 23:38:43 5: myMPD, rec: albums: 0
2024.01.01 23:38:43 5: myMPD, rec: songs: 0
2024.01.01 23:38:43 5: myMPD, rec: uptime: 0
2024.01.01 23:38:43 5: myMPD, rec: db_playtime: 0
2024.01.01 23:38:43 5: myMPD, rec: db_update: 0
2024.01.01 23:38:43 5: myMPD, rec: playtime: 0
2024.01.01 23:38:43 5: myMPD, rec: file: tunein:station:s24896
2024.01.01 23:38:43 5: myMPD, rec: Time: 0
2024.01.01 23:38:43 5: myMPD, rec: Artist: SWR3
2024.01.01 23:38:43 5: myMPD, rec: Album: SWR3
2024.01.01 23:38:43 5: myMPD, rec: Title: Bad / Michael Jackson
2024.01.01 23:38:43 5: myMPD, rec: Name: Mehr Hits. Mehr Kicks. Einfach SWR3
2024.01.01 23:38:43 5: myMPD, rec: Pos: 0
2024.01.01 23:38:43 5: myMPD, rec: Id: 1
2024.01.01 23:38:43 5: myMPD, rec: X-AlbumUri: tunein:station:s24896
2024.01.01 23:38:44 5: myMPD, mpd_cmd[2] -> command_list_begin
status
stats
currentsong
command_list_end

hier meine config

Code Auswählen

defmod myMPD MPD 192.168.1.31 6660
attr myMPD alias myMPD
attr myMPD devStateIcon play:rc_PLAY:stop stop:rc_STOP:play pause:rc_PAUSE:pause error:icoBlitz
attr myMPD icon it_radio
attr myMPD loadPlaylists 1
attr myMPD player mopidy
attr myMPD titleSplit 0
attr myMPD unknown_artist_image /fhem/icons/1px-spacer
attr myMPD verbose 5

soweit ich herausgefunden habe immer bei einem Playlist Event in dem die Liste scheinbar leer ist

Beta-User · 02 Januar 2024, 09:02:40

Zitat von: Medel am 01 Januar 2024, 23:56:19Ich bekomme immer wieder einen socket error

Na ja, das wird mit verbose 4 bzw. 5 geloggt. Sowas ist in der Regel dann eher kein echtes Problem, oder übersehe ich was?

Medel · 02 Januar 2024, 11:49:36

Es kommt aber auch ein state: error und presence: absend

Ein weiteres Problem das mir aufgefallen ist:
Wenn ich das play Kommando schicke wird der Player gestartet aber der state play nicht angezeigt.
Erst beim 2. play oder beim abfragen des status requests

Medel · 02 Januar 2024, 12:42:26

Hier ist noch der Log von mopidy wenn der Fehler (socket error) auftritt:

Code Auswählen

DEBUG    [MainThread] mopidy.audio.gst Got TAG bus message: tags={'title': ['SWR3 NOW   Die Vormittagsshow'], 'has-crc': [False], 'channel-mode': ['joint-stereo'], 'audio-codec': ['MPEG-1 Layer 3 (MP3)'], 'nominal-bitrate': [128000], 'minimum-bitrate': [128000], 'maximum-bitrate': [128000], 'bitrate': [128000]}
DEBUG    [MainThread] mopidy.audio.actor Audio event: tags_changed(tags=['title'])
DEBUG    [MainThread] mopidy.listener Sending tags_changed to AudioListener: {'tags': ['title']}
DEBUG    [Core-8] mopidy.listener Sending stream_title_changed to CoreListener: {'title': 'SWR3 NOW   Die Vormittagsshow'}
DEBUG    [MpdFrontend-11] mopidy.listener Sending playlist to MpdSession: {}
DEBUG    [AutoplayFrontend-13] mopidy_autoplay.frontend on_event(stream_title_changed, {'title': 'SWR3 NOW   Die Vormittagsshow'})
DEBUG    [MpdSession-18] mopidy_mpd.session Request from [::ffff:192.168.1.34]:59880: idle
DEBUG    [MpdSession-18] mopidy_mpd.session Request from [::ffff:192.168.1.34]:59880: idle
DEBUG    [MpdSession-18] mopidy_mpd.dispatcher Client sent us 'idle', only 'noidle' is allowed while in the idle state
DEBUG    [MpdSession-18] pykka Unregistered MpdSession (urn:uuid:20ffdc5c-f13f-40fc-9590-ff20f795d59d)
DEBUG    [MpdSession-18] pykka Stopped MpdSession (urn:uuid:20ffdc5c-f13f-40fc-9590-ff20f795d59d)
DEBUG    [MpdSession-18] mopidy_mpd.network Actor is shutting down.

Medel · 02 Januar 2024, 15:05:08

Habe mal in der 73_MPD.pm noch ein OK eingefügt:

Code Auswählen

1285     elsif ($_ !~ m{\Aplayer|playlist|mixer|options|update|OK\z}x){

Bisher hatte ich keinen socket error mehr und auch das Problem mit dem Play und der Aktualisierung ist weg. Wird gleich beim erstem Mal richtig angezeigt.
Bitte mal jemand der sich damit besser auskennt darüber schauen und ggf. einpflegen.

Medel · 12 Januar 2024, 15:12:14

Kann sich jemand der sich mit MPD befasst mal darum kümmern?

ZitatHabe mal in der 73_MPD.pm noch ein OK eingefügt:
Code Auswählen
1285 elsif ($_ !~ m{\Aplayer|playlist|mixer|options|update|OK\z}x){
Bisher hatte ich keinen socket error mehr und auch das Problem mit dem Play und der Aktualisierung ist weg. Wird gleich beim erstem Mal richtig angezeigt.
Bitte mal jemand der sich damit besser auskennt darüber schauen und ggf. einpflegen.

Beta-User · 13 Januar 2024, 08:28:26

Zitat von: Medel am 02 Januar 2024, 15:05:08Habe mal in der 73_MPD.pm noch ein OK eingefügt:
Code Auswählen Erweitern
1285 elsif ($_ !~ m{\Aplayer|playlist|mixer|options|update|OK\z}x){Bisher hatte ich keinen socket error mehr und auch das Problem mit dem Play und der Aktualisierung ist weg. Wird gleich beim erstem Mal richtig angezeigt.
Bitte mal jemand der sich damit besser auskennt darüber schauen und ggf. einpflegen.

Hab's auf der Liste, muss aber erst schauen, ob es mit dem normalen MPD damit keine Probleme gibt.
Hoffe, ggf. dieses WE dazu zu kommen.

Medel · 30 März 2024, 12:17:22

Gibt es zu meinem Problem schon was neues

. Habe es immer noch von dem update ausgeschlossen

Beta-User · 12 April 2024, 10:11:39

Zitat von: Medel am 30 März 2024, 12:17:22Gibt es zu meinem Problem schon was neues . Habe es immer noch von dem update ausgeschlossen

Hmmmm, hab's mir angesehen, aber....:

- der OK-Fall ist innerhalb des Codes (im Rahmen der fraglichen while-Schleife) eigentlich auch (mehrfach) berücksichtigt
- Falls das Problem nicht doch ganz woanders herhommt, würde ich eher den "OK"-Teil im Hinblick auf das anscheinend andere Verhalten von mopidity ändern wollen. Dafür bitte deinen Fix rückgängig machen und es mal damit probieren:

Code Auswählen

# ab Zeile 1294
 else { #if ($_ eq "OK")  
 if ($step || AttrVal($name,'player','mpd') eq 'mopidy') {
 print $sock "idle\n";
 } else {
 print $sock "playlistinfo\n";
 }
 $step=2 if ($step==1); 
    } # OK

Medel · 21 April 2024, 16:42:54

So hab's mal getestet. Das Problem wird durch diese Änderung nicht behoben. Was mir aufgefallen ist:
Eine Änderung macht sich erst mit Neustart von Fhem bemerkbar, ein einfaches reload reicht nicht.
Habe mal ein paar Testzeilen eingefügt:

Code Auswählen

     elsif ($_ !~ m{\Aplayer|playlist|mixer|options|update\z}x){
 Log3 $name,5,"$name, Idle test1 : $_";
 Log3 $name,5,"$name, Idle test1 step : $step";
      print $sock "idle\n";
       $step=1;
       readingsSingleUpdate($hash,'last_error',$_,1);
     }
     elsif ($_ eq 'update'){ #might be extended for other new message types w/o further action to FHEM
 Log3 $name,5,"$name, Idle test2 : $_";
 Log3 $name,5,"$name, Idle test2 step : $step";
       print $sock "idle\n";
       $step=1;
     }
     else { #if ($_ eq "OK")  
 Log3 $name,5,"$name, Idle test3 : $_";
 Log3 $name,5,"$name, Idle test3 step : $step";
       if ($step || AttrVal($name,'player','mpd') eq 'mopidy') {
         print $sock "idle\n";
       } else {
         print $sock "playlistinfo\n";
       }
       $step=2 if ($step==1);
    } # OK

Hier der Log, vielleicht hilft es weiter:

Code Auswählen

command_list_end
2024.04.21 16:34:55 5: myMPD, Idle test1 : OK
2024.04.21 16:34:55 5: myMPD, Idle test1 step : 0
2024.04.21 16:35:15 5: myMPD, Idle test3 : playlist
2024.04.21 16:35:15 5: myMPD, Idle test3 step : 1
2024.04.21 16:35:15 5: myMPD, Idle test1 : OK
2024.04.21 16:35:15 5: myMPD, Idle test1 step : 0
2024.04.21 16:35:15 5: myMPD, MPD_EVENT : playlist
2024.04.21 16:35:15 4: myMPD, MPD_EVENT : playlist
2024.04.21 16:35:15 5: myMPD, new Playlist in -> 
2024.04.21 16:35:15 5: myMPD, IdleDone -> myMPD|socket error
2024.04.21 16:35:15 4: myMPD, idle error -> socket error
2024.04.21 16:36:15 4: myMPD, Idle new PID : 1130680
2024.04.21 16:36:15 5: myMPD, mpd_cmd[1] -> command_list_begin
status