C3PO Plugin verisone 0.1alfa

[antonellocaroli]

Log LMS spedito....

[marcoc1712]

Ciao,

Non mi hai mandato le impostazioni del lettore e probabilmente l'arcano è li:

[16-01-06 14:46:38.8299] Slim::Player::Song::open (408) Transcoder: streamMode=I, streamformat=pcm

Sei con STDIN Abilitato, Giovanni era in SEEK abilitato, due percorsi completamente diversi.

[16-01-06 14:46:38.8333] Slim::Player::Pipeline::new (96) Launching process with command: "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\socketwrapper.exe" -D -i 60337 -o 60336 -c "\"C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe\" -dcs --totally-silent -- - | \"C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\sox.exe\" -q -t wav - -t wav -r 384000 -c 2 -4 -s - gain -3 rate -v -M -a -b 90.7 384000"

...di nuovo lui...


[16-01-06 14:46:38.8488] Slim::Player::StreamingController::_Stream (1265) 50:e5:49:cc:b4:29: stream
...
[16-01-06 14:46:38.8498] Slim::Player::StreamingController::_eventAction (303) 50:e5:49:cc:b4:29: Play - new state BUFFERING-STREAMING
...
[16-01-06 14:46:39.0779] Slim::Player::StreamingController::playerTrackStarted (2177) 50:e5:49:cc:b4:29
[16-01-06 14:46:39.0780] Slim::Player::StreamingController::_eventAction (271) 50:e5:49:cc:b4:29: Started in BUFFERING-STREAMING -> Slim::Player::StreamingController::_Playing
[16-01-06 14:46:39.0781] Slim::Player::StreamingController::_setPlayingState (2354) new playing state PLAYING
[16-01-06 14:46:39.0782] Slim::Player::StreamingController::_Playing (361) Song 2 has now started playing
[16-01-06 14:46:39.0785] Slim::Player::StreamingController::_Playing (390) Song queue is now 2
[16-01-06 14:46:39.0786] Slim::Player::StreamingController::_eventAction (303) 50:e5:49:cc:b4:29: Started - new state PLAYING-STREAMING
...
[16-01-06 14:46:41.1986] Slim::Player::Pipeline::sysread (304) Attempting to write to pipeline writer
[16-01-06 14:46:41.1988] Slim::Player::StreamingController::playerReadyToStream (2195) 50:e5:49:cc:b4:29
[16-01-06 14:46:41.1990] Slim::Player::StreamingController::_eventAction (271) 50:e5:49:cc:b4:29: ReadyToStream in PLAYING-STREAMING -> Slim::Player::StreamingController::_NextIfMore
[16-01-06 14:46:41.1991] Slim::Player::StreamingController::_setStreamingState (2363) new streaming state IDLE
[16-01-06 14:46:41.1992] Slim::Player::StreamingController::nextsong (882) The next song is number 0, was 2
[16-01-06 14:46:41.1993] Slim::Player::StreamingController::_eventAction (303) 50:e5:49:cc:b4:29: ReadyToStream - new state PLAYING-IDLE

Il player ha inviato lo stop dop circa 2 secondi nel corso dei quali ha suonato.


Su squeezelite-R2:


[14:34:12.918239] sendRESP:202 RESP
...
[14:34:13.102368] sendSTAT:171 STAT: STMs
[14:34:13.102404] sendSTAT:171 STAT: STMt
[14:34:14.103434] sendSTAT:171 STAT: STMt
[14:34:14.891739] pcm_decode:237 reached end of audio
[14:34:14.891807] decode_thread:99 decode complete
[14:34:14.891845] sendSTAT:171 STAT: STMd
[14:34:15.892559] sendSTAT:171 STAT: STMt
[14:34:16.346174] process:494 strm
[14:34:16.346202] process_strm:256 strm command t
[14:34:16.346212] sendSTAT:171 STAT: STMt
[14:34:17.347244] sendSTAT:171 STAT: STMt
[14:34:18.348315] sendSTAT:171 STAT: STMt
[14:34:19.349397] sendSTAT:171 STAT: STMt
[14:34:20.350455] slimproto_run:679 output underrun
[14:34:20.350484] sendSTAT:171 STAT: STMu
[14:34:21.344241] process:494 strm
[14:34:21.344272] process_strm:256 strm command t
[14:34:21.344289] sendSTAT:171 STAT: STMt
[14:34:22.345344] slimproto_run:689 output timeout
[14:34:26.169743] slimproto_stop:920 slimproto stop


Probabilmente gli orari assoluti non sono allineati, OK, ma non mi torna nemmeno il Delta relativo:

[16-01-06 14:46:38.8488] Slim::Player::StreamingController::_Stream (1265) 50:e5:49:cc:b4:29: stream
[14:34:16.346202] process_strm:256 strm command t


[16-01-06 14:46:41.1993] Slim::Player::StreamingController::_eventAction (303) 50:e5:49:cc:b4:29: ReadyToStream - new state PLAYING-IDLE
[14:34:20.350484] sendSTAT:171 STAT: STMu

Il server si ferma dopo 2.35 secondi squeezelite dopo 4.01, se le cose stanno così NON è squeezelite a fermare il server, ma il server che si ferma (senza fornire segnalazione) e squeezelite se ne accorge, si ferma e manda la segnalazione.

Sei comunque in pieno dominio di LMS, C-3PO ha finito il suo compito quando ha scritto la riga di comando. Potresti provare, per sicurezza, a scivere esattamente la stessa riga nel convert.conf e vedere che risultati ti da:

flac pcm * *
# IFRD:{RESAMPLE=-r %d}
[flac] -dcs --totally-silent $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t wav -r 384000 -c 2 -4 -s - gain -3 rate -v -M -a -b 90.7 384000

Dovesse dare risultati diversi ne rimarrei molto sorpreso e, dovesse essere così, vedrei volentieri i log per capire cosa fa di diverso.

Per chiarezza, però, questo NON è il problema riscontrato da Giovanni, lui è in Linux, non c'è SOCKETWRAPPER e per di più usa SEEK e non STDIN, potrebbe essere analogo, ma il log di squeezeite che ho visto è completamente diverso.

A mio avviso stai però chiedendo troppo al tuo sistema e/o non hai buffer sufficientemente grandi (stai tenendo i buffer di default e sei a 384KHz/32 = 17.5 volte..). Proverei a seguire le indicazioni di calcolo che ho dato per i buffers e se non dovesse bastare passerei 24 bit e 192KHz, il che potrebbe rendere meno 'sensibile' Squeezelite-R2 a sbalzi del server, 'tamponando' sue lacune.

Spero aiuti.

EDIT: se non ricordo male, a te esce la finestra di SocketWrapper durante il debug, vedi se ci sono messaggi particolari li che non compiaono nel log del server.

[antonellocaroli]

Ciao,

Non mi hai mandato le impostazioni del lettore e probabilmente l'arcano è li:

[16-01-06 14:46:38.8299] Slim::Player::Song::open (408) Transcoder: streamMode=I, streamformat=pcm

Sei con STDIN Abilitato, Giovanni era in SEEK abilitato, due percorsi completamente diversi.

[16-01-06 14:46:38.8333] Slim::Player::Pipeline::new (96) Launching process with command: "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\socketwrapper.exe" -D -i 60337 -o 60336 -c "\"C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe\" -dcs --totally-silent -- - | \"C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\sox.exe\" -q -t wav - -t wav -r 384000 -c 2 -4 -s - gain -3 rate -v -M -a -b 90.7 384000"

...di nuovo lui...


[16-01-06 14:46:38.8488] Slim::Player::StreamingController::_Stream (1265) 50:e5:49:cc:b4:29: stream
...
[16-01-06 14:46:38.8498] Slim::Player::StreamingController::_eventAction (303) 50:e5:49:cc:b4:29: Play - new state BUFFERING-STREAMING
...
[16-01-06 14:46:39.0779] Slim::Player::StreamingController::playerTrackStarted (2177) 50:e5:49:cc:b4:29
[16-01-06 14:46:39.0780] Slim::Player::StreamingController::_eventAction (271) 50:e5:49:cc:b4:29: Started in BUFFERING-STREAMING -> Slim::Player::StreamingController::_Playing
[16-01-06 14:46:39.0781] Slim::Player::StreamingController::_setPlayingState (2354) new playing state PLAYING
[16-01-06 14:46:39.0782] Slim::Player::StreamingController::_Playing (361) Song 2 has now started playing
[16-01-06 14:46:39.0785] Slim::Player::StreamingController::_Playing (390) Song queue is now 2
[16-01-06 14:46:39.0786] Slim::Player::StreamingController::_eventAction (303) 50:e5:49:cc:b4:29: Started - new state PLAYING-STREAMING
...
[16-01-06 14:46:41.1986] Slim::Player::Pipeline::sysread (304) Attempting to write to pipeline writer
[16-01-06 14:46:41.1988] Slim::Player::StreamingController::playerReadyToStream (2195) 50:e5:49:cc:b4:29
[16-01-06 14:46:41.1990] Slim::Player::StreamingController::_eventAction (271) 50:e5:49:cc:b4:29: ReadyToStream in PLAYING-STREAMING -> Slim::Player::StreamingController::_NextIfMore
[16-01-06 14:46:41.1991] Slim::Player::StreamingController::_setStreamingState (2363) new streaming state IDLE
[16-01-06 14:46:41.1992] Slim::Player::StreamingController::nextsong (882) The next song is number 0, was 2
[16-01-06 14:46:41.1993] Slim::Player::StreamingController::_eventAction (303) 50:e5:49:cc:b4:29: ReadyToStream - new state PLAYING-IDLE

Il player ha inviato lo stop dop circa 2 secondi nel corso dei quali ha suonato.


Su squeezelite-R2:


[14:34:12.918239] sendRESP:202 RESP
...
[14:34:13.102368] sendSTAT:171 STAT: STMs
[14:34:13.102404] sendSTAT:171 STAT: STMt
[14:34:14.103434] sendSTAT:171 STAT: STMt
[14:34:14.891739] pcm_decode:237 reached end of audio
[14:34:14.891807] decode_thread:99 decode complete
[14:34:14.891845] sendSTAT:171 STAT: STMd
[14:34:15.892559] sendSTAT:171 STAT: STMt
[14:34:16.346174] process:494 strm
[14:34:16.346202] process_strm:256 strm command t
[14:34:16.346212] sendSTAT:171 STAT: STMt
[14:34:17.347244] sendSTAT:171 STAT: STMt
[14:34:18.348315] sendSTAT:171 STAT: STMt
[14:34:19.349397] sendSTAT:171 STAT: STMt
[14:34:20.350455] slimproto_run:679 output underrun
[14:34:20.350484] sendSTAT:171 STAT: STMu
[14:34:21.344241] process:494 strm
[14:34:21.344272] process_strm:256 strm command t
[14:34:21.344289] sendSTAT:171 STAT: STMt
[14:34:22.345344] slimproto_run:689 output timeout
[14:34:26.169743] slimproto_stop:920 slimproto stop


Probabilmente gli orari assoluti non sono allineati, OK, ma non mi torna nemmeno il Delta relativo:

[16-01-06 14:46:38.8488] Slim::Player::StreamingController::_Stream (1265) 50:e5:49:cc:b4:29: stream
[14:34:16.346202] process_strm:256 strm command t


[16-01-06 14:46:41.1993] Slim::Player::StreamingController::_eventAction (303) 50:e5:49:cc:b4:29: ReadyToStream - new state PLAYING-IDLE
[14:34:20.350484] sendSTAT:171 STAT: STMu

Il server si ferma dopo 2.35 secondi squeezelite dopo 4.01, se le cose stanno così NON è squeezelite a fermare il server, ma il server che si ferma (senza fornire segnalazione) e squeezelite se ne accorge, si ferma e manda la segnalazione.

Sei comunque in pieno dominio di LMS, C-3PO ha finito il suo compito quando ha scritto la riga di comando. Potresti provare, per sicurezza, a scivere esattamente la stessa riga nel convert.conf e vedere che risultati ti da:

flac pcm * *
# IFRD:{RESAMPLE=-r %d}
[flac] -dcs --totally-silent $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t wav -r 384000 -c 2 -4 -s - gain -3 rate -v -M -a -b 90.7 384000

Dovesse dare risultati diversi ne rimarrei molto sorpreso e, dovesse essere così, vedrei volentieri i log per capire cosa fa di diverso.

Per chiarezza, però, questo NON è il problema riscontrato da Giovanni, lui è in Linux, non c'è SOCKETWRAPPER e per di più usa SEEK e non STDIN, potrebbe essere analogo, ma il log di squeezeite che ho visto è completamente diverso.

A mio avviso stai però chiedendo troppo al tuo sistema e/o non hai buffer sufficientemente grandi (stai tenendo i buffer di default e sei a 384KHz/32 = 17.5 volte..). Proverei a seguire le indicazioni di calcolo che ho dato per i buffers e se non dovesse bastare passerei 24 bit e 192KHz, il che potrebbe rendere meno 'sensibile' Squeezelite-R2 a sbalzi del server, 'tamponando' sue lacune.

Spero aiuti.

EDIT: se non ricordo male, a te esce la finestra di SocketWrapper durante il debug, vedi se ci sono messaggi particolari li che non compiaono nel log del server.

La stringa non funziona uguale.

credo che tu abbia centrato il problema nel mio caso....mettento a 192 non si presenta il problema....

Quali erano le indicazioni sul calcolo del buffer? mi sfuggono....

[marcoc1712]

La stringa non funziona uguale.

credo che tu abbia centrato il problema nel mio caso....mettento a 192 non si presenta il problema....

Quali erano le indicazioni sul calcolo del buffer? mi sfuggono....

Qui.

Ciao.