LBS(Sammlung) Squeeze

[Marha]

Nein, der 805er hat überhaupt nichts mit MP3, squeeze etc zu tun. Der wird in einem komplett anderen Bereich verwendet.

[jonofe]

Nutzt du den 805er denn um ein MP3 zu erzeugen?

[Marha]

In Verbindung mit Squeezelite ab einer bestimmten Version und dem Durchsage LBS wurde hier bereits von mehreren Problemen berichtet, dass die Playlist nach einer Durchsage nicht fortgesetzt wird, die Durchsage eine bestimmte Länge haben müssen usw. Seit ca 3 Wochen habe ich die aktuelle Version des Picoreplayer mit dem Durchsage LBS in Verwendung ohne jegliche Probleme. Playlisten werden fortgesetzt auch wenn die Durchsage unter 3 sek liegt. Mit Vorgängeversionen hatte ich auch die benannten Probleme.
In einer anderen Logik verwende ich nun den LBS (19000805). Nach der Projektaktivierung habe ich nun auch wieder die beschrieben Probleme mit dem Durchsage LBS und Squeezelite. Ich bin mir nicht ganz sicher, ob die Probleme allein bei Squeezelite zu suchen sind oder ob vielleicht der ein oder andere LBS noch etwas dazu beiträgt. In den Logs könnte ich nichts Auffälliges erkennen. Vielleicht hat jemand neue Erkenntnisse und kann dazu noch etwas sagen. Gibt's Ideen, wie man dem Ganzen auf die Spur kommen kann?

[wintermute]

Ist ja fast schade, wenn wir jetzt LogLevel 10 gar nicht mehr brauchen

Es gibt schlimmeres
Danke fuer die Rueckmeldung!

[mfd]

Ich habe mal eine Version 0.8 hochgeladen, da sind nicht viele Aenderungen drin. Wohl aber der Fix mit den Reconnects der bei Dir zu Problemen gefuehrt hat und - extra fuer Dich :

Der Playercount scheint bei der aktuellen Version einwandfrei zu funktionieren. Es gibt bisher keinerlei Abweichungen mehr. Danke dafür!
Ist ja fast schade, wenn wir jetzt LogLevel 10 gar nicht mehr brauchen

[wintermute]

Ich habe mal eine Version 0.8 hochgeladen, da sind nicht viele Aenderungen drin. Wohl aber der Fix mit den Reconnects der bei Dir zu Problemen gefuehrt hat und - extra fuer Dich : Wenn man den LogLevel auf 10 setzt wird neben den normalen Ausgaben auch jedesmal der aktuelle Playercount mitgeschrieben. Vllt kann man damit genauer rausfinden was da passiert...

[mfd]

Ich taete das fuer die naechste Version korrigieren und das Debugging im Server-LBS etwas feiner granulieren. Koennten wir das dann nochmal ausprobieren?

Ja, selbstverständlich, wenn ich schon beim Code nicht behilflich sein kann, dann doch wenigstens beim (Beta-) Testen
Es kann auch gut sein, dass ich durch das Ganze Rumgemurkse im LBS-Code vielleicht etwas falsch gemacht habe und deshalb ein so seltsames Verhalten erzeuge...

[wintermute]

Ist mir gerade voellig unerklaerlich
Aber beim aufmerksamen Durchsehen und Vergleichen der Logfiles ist mir aufgefallen, dass da ein konzeptioneller Fehler beim anfaenglichen Abfragen der Player ist. Ich taete das fuer die naechste Version korrigieren und das Debugging im Server-LBS etwas feiner granulieren. Koennten wir das dann nochmal ausprobieren?

[mfd]

Bei mir sieht es so aus:

2017-05-11 15:14:22    195175    2754    6    (ID4) Informational: daemon terminating
2017-05-11 15:14:22    195502    2754    6    (ID4) Informational: Sending 'exit'
2017-05-11 15:14:36    788500    15269    6    (ID4) Informational: Sending 'version ?'
2017-05-11 15:14:36    893388    15269    6    (ID4) Informational: RECV: version 7.9.0
2017-05-11 15:14:36    895271    15269    6    (ID4) Informational: Sending 'listen 1'
2017-05-11 15:14:36    999905    15269    6    (ID4) Informational: RECV: listen 1
2017-05-11 15:14:37    000199    15269    6    (ID4) Informational: Sending 'players'
2017-05-11 15:14:37    104321    15269    6    (ID4) Informational: [COLOR=#FF0000]RECV: players count:4[/COLOR]
2017-05-11 15:14:37    105933    15269    6    (ID4) Informational: Sending 'players 0 1'
2017-05-11 15:14:37    211760    15269    6    (ID4) Informational: RECV: players 0 1 count:4 playerindex:0 playerid:ab:cd:ef:12:34:04 uuid: ip:192.168.xyz.xyz:40242 name:Outdoor model:squeezelite isplayer:1 displaytype:none canpoweroff:1 connected:0
2017-05-11 15:14:37    212254    15269    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:04 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy'
2017-05-11 15:14:37    352061    15269    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 status - 1 subscribe:0 tags
2017-05-11 15:14:37    354499    15269    6    (ID4) Informational: Sending 'players 1 2'
2017-05-11 15:14:37    461955    15269    6    (ID4) Informational: RECV: players 1 2 count:4 playerindex:1 playerid:ab:cd:ef:12:34:02 uuid: ip:192.168.xyz.xyz:40238 name:Bad model:squeezelite isplayer:1 displaytype:none canpoweroff:1 connected:0 playerindex:2 playerid:ab:cd:ef:12:34:01 uuid: ip:192.168.xyz.xyz:40236 name:Wohnraum model:squeezelite isplayer:1 displaytype:none canpoweroff:1 connected:0
2017-05-11 15:14:37    462450    15269    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:02 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy'
2017-05-11 15:14:37    578018    15269    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 status - 1 subscribe:0 tags:
2017-05-11 15:14:37    580549    15269    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy'
2017-05-11 15:14:37    700894    15269    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:
2017-05-11 15:14:37    703356    15269    6    (ID4) Informational: Sending 'players 2 3'
2017-05-11 15:14:37    810425    15269    6    (ID4) Informational: RECV: players 2 3 count:4 playerindex:2 playerid:ab:cd:ef:12:34:01 uuid: ip:192.168.xyz.xyz:40236 name:Wohnraum model:squeezelite isplayer:1 displaytype:none canpoweroff:1 connected:0 playerindex:3 playerid:ab:cd:ef:12:34:03 uuid: ip:192.168.xyz.xyz:40240 name:Dachboden model:squeezelite isplayer:1 displaytype:none canpoweroff:1 connected:0
2017-05-11 15:14:37    810911    15269    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy'
2017-05-11 15:14:37    931679    15269    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:
2017-05-11 15:14:37    934262    15269    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:03 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy'
2017-05-11 15:14:38    048835    15269    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy player_name:Dachboden player_connected:0 power:0 signalstrength:0 mode:stop time:0 rate:1 duration:2.091 can_seek:1 mixer volume:4 playlist repeat:0 playlist shuffle:0 playlist mode:off seq_no:0 playlist_cur_index:0 playlist_timestamp:1494478946.22672 playlist_tracks:2 digital_volume_control:1
2017-05-11 15:14:38    050508    15269    6    (ID4) Informational: Sending 'players 3 4'
2017-05-11 15:14:38    156672    15269    6    (ID4) Informational: RECV: players 3 4 count:4 playerindex:3 playerid:ab:cd:ef:12:34:03 uuid: ip:192.168.xyz.xyz:40240 name:Dachboden model:squeezelite isplayer:1 displaytype:none canpoweroff:1 connected:0
2017-05-11 15:14:38    157041    15269    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:03 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy'
2017-05-11 15:14:38    271472    15269    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy player_name:Dachboden player_connected:0 power:0 signalstrength:0 mode:stop time:0 rate:1 duration:2.091 can_seek:1 mixer volume:4 playlist repeat:0 playlist shuffle:0 playlist mode:off seq_no:0 playlist_cur_index:0 playlist_timestamp:1494478946.22672 playlist_tracks:2 digital_volume_control:1
2017-05-11 15:14:38    273204    15269    6    (ID4) Informational: Verbunden mit LMS an 192.168.1.55:9090, Version 7.9.0, [COLOR=#FF0000]5 Player verbunden[/COLOR]
2017-05-11 15:14:38    274820    15269    6    (ID4) Informational: Setze KommandoKO aus Queue, 5 Elemente in Queue verbleibend.
2017-05-11 15:14:38    777260    15269    6    (ID4) Informational: Setze KommandoKO aus Queue, 4 Elemente in Queue verbleibend.
2017-05-11 15:14:39    279978    15269    6    (ID4) Informational: Setze KommandoKO aus Queue, 3 Elemente in Queue verbleibend.
2017-05-11 15:14:39    782615    15269    6    (ID4) Informational: Setze KommandoKO aus Queue, 2 Elemente in Queue verbleibend.
2017-05-11 15:14:40    285151    15269    6    (ID4) Informational: Setze KommandoKO aus Queue, 1 Elemente in Queue verbleibend.
2017-05-11 15:14:40    787564    15269    6    (ID4) Informational: Setze KommandoKO aus Queue, 0 Elemente in Queue verbleibend.
2017-05-11 15:15:54    374990    15269    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 client reconnect
2017-05-11 15:15:54    375312    15269    6    (ID4) Informational: client ab:cd:ef:12:34:01 reconnected

Ein paar Zeilen habe ich nach "tags" gekürzt.

[wintermute]

Irgendwie wird der letzte erkannte Wert offenbar beibehalten, auch nach EDOMI-Neustart.

Hmja... in dem Fall bin ich raus, das liegt dann am LMS (puh)

Der Server-LBS fragt beim Start (also auch beim Edomi-Start) den Count ab, kann man im Logfile vom 19000200 nachverfolgen:

2017-05-11 16:16:29    843827    1189    6    (ID127) Informational: Sending 'version ?'
2017-05-11 16:16:29    946840    1189    6    (ID127) Informational: RECV: version 7.9.0
2017-05-11 16:16:29    950237    1189    6    (ID127) Informational: Sending 'listen 1'
2017-05-11 16:16:30    054902    1189    6    (ID127) Informational: RECV: listen 1
2017-05-11 16:16:30    055432    1189    6    (ID127) Informational: [COLOR=#FF0000]Sending 'players'[/COLOR]
2017-05-11 16:16:30    159085    1189    6    (ID127) Informational: [COLOR=#FF0000]RECV: players   count:3[/COLOR]
...
2017-05-11 16:16:30    961961    1189    6    (ID127) Informational: Verbunden mit LMS an blahblah.localnet:9090, Version 7.9.0, [COLOR=#FF0000]3 Player verbunden[/COLOR]

Oder sieht das bei Dir nochmal anders aus?

[jonofe]

hast du vielleicht irgendwelche iKOs als remanent definiert, die nicht remanent sein sollten?

[mfd]

Kannst Du das Verhalten irgendwie forcieren? Und stimmt der Playercount wenn der Server-LBS startet oder ist er da schon flasch?

Hm, jetzt wo ich testhalber die Player ausgeknipst und EDOMI daraufhin neu gestartet habe, denkt der Sever LBS er hätte 9 Player .
Anscheinend hatte er die 5 noch drin bevor ich neugestartet habe und dann die 4 Instanzen korrekt dazugezählt...

Das läßt sich jetzt auch reproduzieren, jetzt habe ich bei allen Instanzen "aus" 5 als Wert und bei allen Instanzen "ein" 9.
Irgendwie wird der letzte erkannte Wert offenbar beibehalten, auch nach EDOMI-Neustart.

Zuvor war wohl die eine Instanz irgendwie ein Überbleibsel von dem vorherigen Kuddelmuddel und jetzt sind es 5...

[wintermute]

Hmm... ich kann das nicht nachvollziehen
Bei mir werden momentan 3 Player angezeigt, was faktisch richtig ist - es gibt also kein grundlegendes Problem (also immer einer zu viel oder zu wenig oder so).
Technisch ist es so, dass der Server-LBS beim Start den Playercount abfragt. Der Wert wird nur dann (und nur dann) geaendert, wenn ein Connect, Disconnect oder Reconnect auftritt - also dann je nachdem um eins erhoeht oder erniedrigt - eine zwischenzeitliche Abfrage des Playercounts erfolgt nicht. Reconnects wurden vorher nicht gezaehlt, dadurch kam es zu den absurden Werten, aber jetzt seh ich in deinem Log exakt 4 disconnects und dann wieder 4 connects. Der resultierende Wert sollte also korrekt sein... sollte...
Wieso da bei Dir jetzt einer uebrig bleibt verstehe ich nicht. Und ich kanns dummerweise auch nicht nachvollziehen.
Kannst Du das Verhalten irgendwie forcieren? Und stimmt der Playercount wenn der Server-LBS startet oder ist er da schon flasch?
Was man nochmal testen koennte, waere den aktuellen Playercount mit ins Server-Log zu werfen oder - wenn alle Stricke reissen - zwischendurch immer mal den aktuellen Wert abfragen. Letzteres taet ich aber gern vermeiden, denn das behebt das Problem ja nicht wirklich

Wegen dem leeren Wert beim Scannen: ich teste in der Logik bei binaeren Daten eigentlich immer "auf value". Auch in der Visu (zB bei dynamischen Designs) also einen default-Wert fuer "nein" und das dynamische Design greift dann bei Wert 1 fuer "ja". Aber wie gesagt, wenn konkret Bedarf besteht kann ich das reinbasteln, keine Sache.

[mfd]

Probier mal 7, ich glaube der Server unterscheidet (noch) nicht wirklich die LogLevel und schreibt alles als "Debug" - was dann der 7 entspraeche.

So, hier mal ein Auszug, um 01:00 uhr werden alle Player automatisch ausgeknipst und um 07:00 Uhr wieder an.

2017-05-09 23:52:25    496648    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 4 Elemente in Queue verbleibend.
2017-05-09 23:52:25    498817    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 3 Elemente in Queue verbleibend.
2017-05-09 23:52:26    000888    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 2 Elemente in Queue verbleibend.
2017-05-09 23:52:26    503084    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 1 Elemente in Queue verbleibend.
2017-05-09 23:52:27    005394    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 0 Elemente in Queue verbleibend.
2017-05-10 01:00:19    173793    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 client disconnect
2017-05-10 01:00:19    174113    24144    6    (ID4) Informational: client ab:cd:ef:12:34:02 disconnected
2017-05-10 01:00:19    175529    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 client disconnect
2017-05-10 01:00:19    175817    24144    6    (ID4) Informational: client ab:cd:ef:12:34:01 disconnected
2017-05-10 01:00:19    177125    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 client disconnect
2017-05-10 01:00:19    177457    24144    6    (ID4) Informational: client ab:cd:ef:12:34:03 disconnected
2017-05-10 01:00:19    178721    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 client disconnect
2017-05-10 01:00:19    178982    24144    6    (ID4) Informational: client ab:cd:ef:12:34:04 disconnected
2017-05-10 01:00:19    682164    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 status - 1 subscribe:0 tags:aAcCdDef - gekürzt
2017-05-10 01:00:19    685025    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:aAcCdDef - gekürzt
2017-05-10 01:00:19    687387    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 status - 1 subscribe:0 tags:aAcCdDef - gekürzt
2017-05-10 01:00:19    688865    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 status - 1 subscribe:0 tags:aAcCdDef - gekürzt
2017-05-10 01:00:19    691029    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 3 Elemente in Queue verbleibend.
2017-05-10 01:00:19    693114    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 2 Elemente in Queue verbleibend.
2017-05-10 01:00:20    195362    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 1 Elemente in Queue verbleibend.
2017-05-10 01:00:20    697616    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 0 Elemente in Queue verbleibend.
2017-05-10 01:05:19    045077    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 client forget
2017-05-10 01:05:19    045440    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy error:invalid player
2017-05-10 01:05:19    045957    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 alarm _cmd
2017-05-10 01:05:19    046114    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 client forget
2017-05-10 01:05:19    046271    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy error:invalid player
2017-05-10 01:05:19    046772    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 alarm _cmd
2017-05-10 01:05:19    046930    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 client forget
2017-05-10 01:05:19    047086    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy error:invalid player
2017-05-10 01:05:19    047574    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 alarm _cmd
2017-05-10 01:05:19    047738    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 client forget
2017-05-10 01:05:19    047893    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy error:invalid player
2017-05-10 01:05:19    048367    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 alarm _cmd
2017-05-10 01:05:19    048538    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 3 Elemente in Queue verbleibend.
2017-05-10 01:05:19    050565    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 2 Elemente in Queue verbleibend.
2017-05-10 01:05:19    552643    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy error:invalid player
2017-05-10 01:05:19    553236    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy error:invalid player
2017-05-10 01:05:19    553706    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy error:invalid player
2017-05-10 01:05:19    554136    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 status - 1 subscribe:0 tags:aAcCdDefgiIjJKlLNoOqrstTuxy error:invalid player
2017-05-10 01:05:19    554589    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 5 Elemente in Queue verbleibend.
2017-05-10 01:05:19    556605    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 4 Elemente in Queue verbleibend.
2017-05-10 01:05:20    058789    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 3 Elemente in Queue verbleibend.
2017-05-10 01:05:20    560976    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 2 Elemente in Queue verbleibend.
2017-05-10 01:05:21    063096    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 1 Elemente in Queue verbleibend.
2017-05-10 01:05:21    565257    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 0 Elemente in Queue verbleibend.
2017-05-10 01:51:24    249967    24144    6    (ID4) Informational: Sende Keepalive zur Datenbank
2017-05-10 03:28:41    485807    24144    6    (ID4) Informational: RECV: rescan playlists
2017-05-10 03:28:41    486185    24144    6    (ID4) Informational: RECV: prefset server registeredhandlers ARRAY(0x6440580)
2017-05-10 03:28:46    492561    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379834.37401||importer||/volume1/LMS/Playlists|discovering_playlist||1||0||
2017-05-10 03:28:46    492949    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379834||importer||/volume1/LMS/Playlists|discovering_playlist||16||16||1494379834.81433
2017-05-10 03:28:46    493158    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379834.86403||importer||/volume1/LMS/Playlists|playlist_new||1||1||
2017-05-10 03:28:46    493376    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379834||importer||/volume1/LMS/Playlists|playlist_new||1||1||1494379834.91129
2017-05-10 03:28:46    493576    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379834.93431||importer||plugin_fulltext||1||7||
2017-05-10 03:28:46    994824    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379834||importer||plugin_fulltext||7||7||1494379835.48169
2017-05-10 03:28:46    995148    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379835.50759||importer||updateStandaloneArtwork||1||20||
2017-05-10 03:28:47    496423    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379835||importer||updateStandaloneArtwork||20||20||1494379836.05746
2017-05-10 03:28:50    000098    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379836.08763||importer||dboptimize||1||2||
2017-05-10 03:29:18    033091    24144    6    (ID4) Informational: RECV: scanner notify progress:1494379836||importer||dboptimize||2||2||1494379866.23263
2017-05-10 03:29:18    033457    24144    6    (ID4) Informational: RECV: scanner notify end
2017-05-10 03:29:18    033632    24144    6    (ID4) Informational: RECV: scanner notify exit
2017-05-10 03:29:20    036623    24144    6    (ID4) Informational: RECV: rescan done
2017-05-10 03:29:20    038098    24144    6    (ID4) Informational: RECV: prefset plugin.fulltext popularTerms ARRAY(0x621e5d8)
2017-05-10 03:51:25    069288    24144    6    (ID4) Informational: Sende Keepalive zur Datenbank
2017-05-10 05:51:26    378854    24144    6    (ID4) Informational: Sende Keepalive zur Datenbank
2017-05-10 07:00:34    178009    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 client new
2017-05-10 07:00:34    178389    24144    6    (ID4) Informational: client ab:cd:ef:12:34:01 connected
2017-05-10 07:00:34    179886    24144    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:aAcCdDef - gekürzt
2017-05-10 07:00:36    282433    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 alarm _cmd
2017-05-10 07:00:36    282618    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 prefset server playingAtPowerOff
2017-05-10 07:00:36    282776    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 stop
2017-05-10 07:00:36    282926    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 playlist addtracks listref ARRAY(0x6449678) index:0
2017-05-10 07:00:36    283097    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 0 Elemente in Queue verbleibend.
2017-05-10 07:00:36    285320    24144    6    (ID4) Informational: RECV: rescan done
2017-05-10 07:00:36    286628    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:aAcCdDef - gekürzt
2017-05-10 07:00:36    289084    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 0 Elemente in Queue verbleibend.
2017-05-10 07:00:36    792058    24144    6    (ID4) Informational: RECV: prefset plugin.fulltext popularTerms ARRAY(0x61e5a28)
2017-05-10 07:00:36    792412    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 menustatus ARRAY(0x5885168) remove ab:cd:ef:12:34:01
2017-05-10 07:00:36    792596    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 menustatus ARRAY(0x62c2940) add ab:cd:ef:12:34:01
2017-05-10 07:00:36    792758    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 client new
2017-05-10 07:00:36    792912    24144    6    (ID4) Informational: client ab:cd:ef:12:34:02 connected
2017-05-10 07:00:36    794208    24144    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:02 status - 1 subscribe:0 tags:aAcCdDef - gekürzt
2017-05-10 07:00:36    930716    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 alarm _cmd
2017-05-10 07:00:36    930939    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 stop
2017-05-10 07:00:36    931092    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 playlist addtracks listref ARRAY(0x644ff90) index:0
2017-05-10 07:00:36    931252    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 client new
2017-05-10 07:00:36    931427    24144    6    (ID4) Informational: client ab:cd:ef:12:34:03 connected
2017-05-10 07:00:36    932775    24144    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:03 status - 1 subscribe:0 tags:aAcCdDef - gekürzt
2017-05-10 07:00:37    035516    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 status - 1 subscribe:0 tags:aAcCdDefgiI - gekürzt
2017-05-10 07:00:37    037678    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:02 status - 1 subscribe:0 tags:aAcCdDefgiI - gekürzt
2017-05-10 07:00:37    040419    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 alarm _cmd
2017-05-10 07:00:37    040591    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 stop
2017-05-10 07:00:37    040741    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:03 playlist addtracks listref ARRAY(0x644ec78) index:0
2017-05-10 07:00:37    040903    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 client new
2017-05-10 07:00:37    041088    24144    6    (ID4) Informational: client ab:cd:ef:12:34:04 connected
2017-05-10 07:00:37    042459    24144    6    (ID4) Informational: Sending 'ab:cd:ef:12:34:04 status - 1 subscribe:0 tags:aAcCdDef - gekürzt
2017-05-10 07:00:37    145225    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 alarm _cmd
2017-05-10 07:00:37    145416    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 stop
2017-05-10 07:00:37    145565    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 playlist addtracks listref ARRAY(0x61eafd0) index:0
2017-05-10 07:00:37    145736    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:01 status - 1 subscribe:0 tags:aAcCdDefgiI - gekürzt
2017-05-10 07:00:37    148062    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 5 Elemente in Queue verbleibend.
2017-05-10 07:00:37    150419    24144    6    (ID4) Informational: RECV: ab:cd:ef:12:34:04 status - 1 subscribe:0 tags:aAcCdDefgiI - gekürzt
2017-05-10 07:00:37    152795    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 5 Elemente in Queue verbleibend.
2017-05-10 07:00:37    154875    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 4 Elemente in Queue verbleibend.
2017-05-10 07:00:37    657166    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 3 Elemente in Queue verbleibend.
2017-05-10 07:00:38    159672    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 2 Elemente in Queue verbleibend.
2017-05-10 07:00:38    661964    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 1 Elemente in Queue verbleibend.
2017-05-10 07:00:39    164438    24144    6    (ID4) Informational: Setze KommandoKO aus Queue, 0 Elemente in Queue verbleibend.

Er wird nicht initialisiert und ist beim Start somit leer, das ist korrekt. Also beim ersten Scan sollte dort eine 1 auftauchen, nach dem Scan dann eine 0.
Stellt das ein Problem dar? Falls ja, kann ich das auch so bauen, dass er mit 0 initialisiert wird.

Nein, ist kein Problem, vielleicht wäre für Visu-Ausgaben ein Anfangswert einfacher, aber sonst fällt mir jetzt keine Anwendung ein wo es einen Unterschied macht.

[wintermute]

Habe es mal mit 1, 5, 6 probiert, aber es wird nichts ausgegeben bzw. existiert gar keine Individual-Log für den Baustein.

Du musst mit mehr Courage an die Sache rangehen
Probier mal 7, ich glaube der Server unterscheidet (noch) nicht wirklich die LogLevel und schreibt alles als "Debug" - was dann der 7 entspraeche. Ich versuch mal dran zu denken das anzupassen und die Level mit in die Hilfe aufzunehmen...

Edit: Was mir nebenbei noch aufgefallen ist, der Ausgang "Scanning" bleibt bei mir leer wenn kein Media Scan stattfindet. Ist das so gewollt, oder sollte dort eine 0 oder ein anderer Wert anliegen?

Er wird nicht initialisiert und ist beim Start somit leer, das ist korrekt. Also beim ersten Scan sollte dort eine 1 auftauchen, nach dem Scan dann eine 0.
Stellt das ein Problem dar? Falls ja, kann ich das auch so bauen, dass er mit 0 initialisiert wird.