Merci pour ton retour.
; mais il y a clairement du mieux clairement, cf. log ci-dessous
Code :
Starting Squeeze2upnp: /volume1/@appstore/SqueezeCenter/Cache/InstalledPlugins/Plugins/UPnPBridge/Bin/squeeze2upnp-x86-64 -Z -I -b 192.168.21.3 -f /volume1/@appstore/SqueezeCenter/Logs/upnpbridge.log -d all=debug -x /volume1/@appstore/SqueezeCenter/prefs/upnpbridge.xml
[21:03:31.830395] main:1543 Starting squeeze2upnp version: v1.8.2.2 (Feb 5 2019 @ 11:12:42)
[21:03:31.830904] output_init:616 failed loading FLAC: libFLAC.so.8: cannot open shared object file: No such file or directory
[21:03:31.830921] register_alac:545 using alac to decode alc
[21:03:31.830938] load_mad:389 dlerror: libmad.so.0: cannot open shared object file: No such file or directory
[21:03:31.831050] load_faad:620 loaded libfaad.so.2
[21:03:31.831060] register_faad:641 using faad to decode aac
[21:03:31.831078] load_vorbis:307 dlerror: libvorbisidec.so.1: cannot open shared object file: No such file or directory
[21:03:31.831086] register_pcm:275 using pcm
[21:03:31.831098] load_flac:248 dlerror: libFLAC.so.8: cannot open shared object file: No such file or directory
[21:03:31.831105] register_m4a_thru:301 using mp4 to aac (ADTS)
[21:03:31.831112] register_flac_thru:247 using flac
[21:03:31.831117] register_thru:106 using thru
[21:03:31.831123] register_soxr:394 using soxr for resampling
[21:03:31.831128] Start:1319 Binding to 192.168.21.3:49152
[21:03:32.017514] AddMRDevice:1210 [0x752480]: adding renderer (JPLAY FEMTO)
[21:03:32.017552] GetProtocolInfo:337 [0x752480]: uPNP GetProtocolInfo
[21:03:32.018328] GetProtocolInfo:348 [0x752480]: ProtocolInfo http-get:*:audio/mpeg:DLNA.ORG_PN=MP3,http-get:*:audio/flac:*,http-get:*:audio/x-flac:*,http-get:*:audio/wav:*,http-get:*:audio/x-wav:*,http-get:*:audio/x-dsf:*,http-get:*:audio/x-dff:*,http-get:*:audio/dsd:*,http-get:*:audio/x-alac:*,http-get:*:audio/m4a:*,http-get:*:audio/x-m4a:*,http-get:*:audio/x-aiff:*,http-get:*:audio/aiff:*,http-get:*:audio/mp4:*,http-get:*:audio/x-ms-wma:*
[21:03:32.018395] stream_thread_init:307 [0x6cd040] streambuf size: 524288
[21:03:32.018585] output_thread_init:567 [0x6cd040] init output media renderer
[21:03:32.018601] decode_thread_init:166 [0x6cd040]: init decode
[21:03:32.018639] resample_init:345 [0x6cd040]: resampling sync recipe: 0x00, flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000, stopband_begin: 0.00000, phase_response: -1.0
[21:03:32.018644] discover_server:793 [0x6cd040] sending discovery
[21:03:32.018678] UpdateThread:1068 Updating configuration /volume1/@appstore/SqueezeCenter/prefs/upnpbridge.xml
[21:03:32.018869] discover_server:825 [0x6cd040] got response from: 192.168.21.3:3483
[21:03:32.018891] slimproto:845 squeezelite [0x6cd040] <=> player [0x752480]
[21:03:32.018902] MasterHandler:896 [0x752480]: subscribe success
[21:03:32.018904] slimproto:846 [0x6cd040] connecting to 192.168.21.3:3483
[21:03:32.018976] slimproto:876 [0x6cd040] connected
[21:03:32.018991] sendHELO:126 [0x6cd040] mac: bb:bb:6b:28:27:74
[21:03:32.019001] sendHELO:127 [0x6cd040] cap: Model=squeezelite,ModelName=SqueezeLite,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=192000,aif,pcm,flc,mp3
[21:03:32.019594] process:491 [0x6cd040] strm
[21:03:32.019613] process_strm:232 [0x6cd040] strm command q
[21:03:32.019623] decode_flush:206 [0x6cd040]: decode flush
[21:03:32.019634] output_flush:562 [0x6cd040]: flush output buffer
[21:03:32.019644] sq_callback:286 [0x752480]: device off or not controlled by LMS
[21:03:32.019732] process:491 [0x6cd040] setd
[21:03:32.019747] sendSETDName:218 [7]: set playername: JPLAY FEMTO
[21:03:32.019773] process:491 [0x6cd040] setd
[21:03:32.019883] process:491 [0x6cd040] aude
[21:03:32.019898] process_aude:383 [0x6cd040] on/off using aude 1
[21:03:32.019909] sq_callback:282 [0x752480]: device set on/off 1
[21:03:32.019997] process:491 [0x6cd040] audg
[21:03:32.020013] process_audg:397 [0x6cd040] (old) audg gainL: 128 gainR: 128
[21:03:32.020023] CtrlSetMute:282 [0x752480]: uPNP mute 0 (cookie (nil))
[21:03:32.020115] CtrlSetVolume:253 [0x752480]: uPNP volume 100 (cookie 0x1)
[21:03:32.099702] AddMRDevice:1210 [0x754688]: adding renderer ([LG] webOS TV)
[21:03:32.099733] GetProtocolInfo:337 [0x754688]: uPNP GetProtocolInfo
[21:03:32.106776] GetProtocolInfo:348 [0x754688]: ProtocolInfo http-get:*:audio/L16;rate=44100;channels=1:DLNA.ORG_PN=LPCM,http-get:*:audio/L16;rate=44100;channels=2:DLNA.ORG_PN=LPCM,http-get:*:audio/mpeg:DLNA.ORG_PN=MP3,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_SM,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_MED,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_TN,http-get:*:image/jpeg:DLNA.ORG_PN=JPEG_LRG_ICO,http-get:*:image/png:DLNA.ORG_PN=PNG_TN,http-get:*:image/png:DLNA.ORG_PN=PNG_SM_ICO,http-get:*:image/png:DLNA.ORG_PN=PNG_LRG_ICO,http-get:*:image/png:DLNA.ORG_PN=PNG_LRG,http-get:*:video/mp4:DLNA.ORG_PN=AVC_MP4_BL_CIF15_AAC_520,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_NTSC,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_PS_PAL,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_HD_KO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_HD_KO_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_HD_KO_ISO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_KO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_KO_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_KO_ISO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_HD_NA,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_HD_NA_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_HD_NA_ISO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_NA,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_NA_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_NA_ISO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_EU,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_SD_EU_T,http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_EU_ISO,http-get:*:video/vnd.dlna.mpeg-tts:DLNA.ORG_PN=MPEG_TS_JP_T,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVHIGH_FULL,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVHIGH_PRO,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVMED_BASE,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVMED_FULL,http-get:*:video/x-ms-wmv:DLNA.ORG_PN=WMVMED_PRO,http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMABASE,http-get:*:audio/x-ms-wma:DLNA.ORG_PN=WMAFULL,http-get:*:audio/mp3:*,http-get:*:audio/mpeg:*,http-get:*:audio/wav:*,http-get:*:audio/mpeg3:*,http-get:*:video/x-ms-wmv:*,http-get:*:video/x-ms-asf:*,http-get:*:video/x-ms-avi:*,http-get:*:video/mpeg:*,http-get:*:video/avi:*,http-get:*:video/mp4:*,http-get:*:video/x-matroska:*,http-get:*:image/jps:*,http-get:*:image/x-jps:*,http-get:*:image/mpo:*,http-get:*:image/png:*,http-get:*:image/bmp:*,http-get:*:image/jpeg:*,http-get:*:audio/x-wav:*,http-get:*:audio/wma:*,http-get:*:audio/x-ogg:*,http-get:*:video/mts:*
[21:03:32.106869] stream_thread_init:307 [0x6d12d8] streambuf size: 524288
[21:03:32.107029] output_thread_init:567 [0x6d12d8] init output media renderer
[21:03:32.107042] decode_thread_init:166 [0x6d12d8]: init decode
[21:03:32.107080] resample_init:345 [0x6d12d8]: resampling sync recipe: 0x00, flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000, stopband_begin: 0.00000, phase_response: -1.0
[21:03:32.107096] UpdateThread:1068 Updating configuration /volume1/@appstore/SqueezeCenter/prefs/upnpbridge.xml
[21:03:32.107115] discover_server:793 [0x6d12d8] sending discovery
[21:03:32.107320] discover_server:825 [0x6d12d8] got response from: 192.168.21.3:3483
[21:03:32.107341] slimproto:845 squeezelite [0x6d12d8] <=> player [0x754688]
[21:03:32.107353] slimproto:846 [0x6d12d8] connecting to 192.168.21.3:3483
[21:03:32.107403] slimproto:876 [0x6d12d8] connected
[21:03:32.107418] sendHELO:126 [0x6d12d8] mac: bb:bb:c1:bc:a3:96
[21:03:32.107428] sendHELO:127 [0x6d12d8] cap: Model=squeezelite,ModelName=SqueezeLite,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=48000,aac,ogg,alc,aif,pcm,mp3
[21:03:32.107968] process:491 [0x6d12d8] strm
[21:03:32.107986] process_strm:232 [0x6d12d8] strm command q
[21:03:32.107995] decode_flush:206 [0x6d12d8]: decode flush
[21:03:32.108006] output_flush:562 [0x6d12d8]: flush output buffer
[21:03:32.108016] sq_callback:286 [0x754688]: device off or not controlled by LMS
[21:03:32.108093] process:491 [0x6d12d8] setd
[21:03:32.108114] sendSETDName:218 [11]: set playername: [LG] webOS TV
[21:03:32.108139] process:491 [0x6d12d8] setd
[21:03:32.108241] process:491 [0x6d12d8] aude
[21:03:32.108257] process_aude:383 [0x6d12d8] on/off using aude 1
[21:03:32.108267] sq_callback:282 [0x754688]: device set on/off 1
[21:03:32.108346] process:491 [0x6d12d8] audg
[21:03:32.108360] process_audg:397 [0x6d12d8] (old) audg gainL: 3 gainR: 3
[21:03:32.115496] MasterHandler:896 [0x754688]: subscribe success
[21:03:32.118683] _ProcessVolume:623 [0x754688]: UPnP Volume local change 7
[21:03:32.118732] cli_open_socket:187 [0x6d12d8]: opened CLI socket 16
[21:03:32.119288] process:491 [0x6d12d8] audg
[21:03:32.119313] process_audg:397 [0x6d12d8] (old) audg gainL: 3 gainR: 3
[21:03:32.241466] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:03:35.511743] UpdateThread:1014 [0x752480] UPnP keep alive: JPLAY FEMTO
[21:03:36.003088] process:491 [0x6cd040] strm
[21:03:36.003113] process:491 [0x6d12d8] strm
[21:03:36.003149] process_strm:232 [0x6d12d8] strm command t
[21:03:36.003149] process_strm:232 [0x6cd040] strm command t
[21:03:36.003167] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:03:36.003179] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:03:36.047095] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:03:36.249781] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:03:41.002021] process:491 [0x6cd040] strm
[21:03:41.002041] process:491 [0x6d12d8] strm
[21:03:41.002069] process_strm:232 [0x6cd040] strm command t
[21:03:41.002087] process_strm:232 [0x6d12d8] strm command t
[21:03:41.002101] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:03:41.002114] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:03:46.000895] process:491 [0x6cd040] strm
[21:03:46.000934] process_strm:232 [0x6cd040] strm command t
[21:03:46.000942] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:03:46.000945] slimproto_run:564 [0x6d12d8] Closing CLI socket 16
[21:03:46.000994] process:491 [0x6d12d8] strm
[21:03:46.001009] process_strm:232 [0x6d12d8] strm command t
[21:03:46.001019] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:03:51.000368] process:491 [0x6d12d8] strm
[21:03:51.000411] process_strm:232 [0x6d12d8] strm command t
[21:03:51.000424] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:03:51.000449] process:491 [0x6cd040] strm
[21:03:51.000477] process_strm:232 [0x6cd040] strm command t
[21:03:51.000481] UpdateThread:952 Presence checking
[21:03:51.000492] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:03:51.087618] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:03:51.290526] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:03:52.292448] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:03:52.495406] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:03:53.854884] UpdateThread:1014 [0x752480] UPnP keep alive: JPLAY FEMTO
[21:03:55.001157] process:491 [0x6cd040] strm
[21:03:55.001179] process:491 [0x6d12d8] strm
[21:03:55.001204] process_strm:232 [0x6cd040] strm command t
[21:03:55.001218] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:03:55.001231] process_strm:232 [0x6d12d8] strm command t
[21:03:55.001250] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:03:55.677414] UpdateThread:1014 [0x752480] UPnP keep alive: JPLAY FEMTO
[21:04:00.000261] process:491 [0x6cd040] strm
[21:04:00.000308] process_strm:232 [0x6cd040] strm command t
[21:04:00.�00308] process:491 [0x6d12d8] strm
[21:04:00.000325] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:00.000346] process_strm:232 [0x6d12d8] strm command t
[21:04:00.000369] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:04:02.607131] process:491 [0x6cd040] strm
[21:04:02.607174] process_strm:232 [0x6cd040] strm command q
[21:04:02.607186] decode_flush:206 [0x6cd040]: decode flush
[21:04:02.607197] output_flush:562 [0x6cd040]: flush output buffer
[21:04:02.607207] AVTStop:217 [0x752480]: uPNP stop (cookie 0x2)
[21:04:02.608066] ActionHandler:694 [0x752480]: Waited action StopResponse
[21:04:02.687773] process:491 [0x6cd040] audg
[21:04:02.687806] process_audg:397 [0x6cd040] (old) audg gainL: 128 gainR: 128
[21:04:02.688541] process:491 [0x6cd040] strm
[21:04:02.688563] process_strm:229 [0x6cd040] strm command s
[21:04:02.688573] process_strm:298 [0x6cd040], strm s autostart: 1 transition period: 10 transition type: 0 codec: p
[21:04:02.688582] sendSTAT:160 [0x6cd040]: STAT:[STMf] msplayed 0
[21:04:02.688607] process_strm:313 [0x6cd040]: set fade mode: 0
[21:04:02.688701] cli_open_socket:187 [0x6cd040]: opened CLI socket 9
[21:04:02.695903] sq_get_metadata:501 [0x6cd040]: idx 0
artist:Céline Dion
album:1 fille & 4 types
title:01 - Tout l'or des hommes
genre:No Genre
duration:179.026
size:0
cover:http://192.168.21.3:9002/music/8ee4edf5/cover.jpg
[21:04:02.695947] codec_open:235 codec open: 'p'
[21:04:02.695972] bind_socket:358 cannot bind socket 16
[21:04:02.695981] bind_socket:367 socket binding 16 on port 49153
[21:04:02.695992] output_start:82 [0x6cd040]: start thread 0
[21:04:02.696019] sq_callback:310 [0x752480]:
artist:Céline Dion
album:1 fille & 4 types
title:01 - Tout l'or des hommes
genre:No Genre
duration:179.026
size:0
cover:
offset:0
[21:04:02.696039] sq_callback:337 [0x752480]: current URI set http://192.168.21.3:49153/bridge-1.wav
[21:04:02.696090] AVTSetURI:89 [0x752480]: DIDL header: <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/">
<item id="1" parentID="0" restricted="1">
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
<res duration="0:02:59.026" protocolInfo="http-get:*:audio/wav:DLNA.ORG_OP=00;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=09700000000000000000000000000000" sampleFrequency="44100" bitsPerSample="16" nrAudioChannels="2" size="31580186">http://192.168.21.3:49153/bridge-1.wav</res>
</item>
</DIDL-Lite>
[21:04:02.696099] AVTSetURI:91 [0x752480]: uPNP setURI http://192.168.21.3:49153/bridge-1.wav (cookie 0x3)
[21:04:02.696229] process_start:1184 [0x6cd040]: codec:p, ch:2, s:16, r:44100
[21:04:02.696242] stream_sock:397 [0x6cd040] connecting to 192.168.21.3:9002
[21:04:02.696297] stream_sock:426 [0x6cd040] header: GET /stream.mp3?player=bb:bb:6b:28:27:74 HTTP/1.0
[21:04:02.696309] sendSTAT:160 [0x6cd040]: STAT:[STMc] msplayed 0
[21:04:02.696331] process:491 [0x6cd040] audg
[21:04:02.696339] process_audg:397 [0x6cd040] (old) audg gainL: 128 gainR: 128
[21:04:02.697110] ActionHandler:694 [0x752480]: Waited action SetAVTransportURIResponse
[21:04:02.741299] stream_thread:190 [0x6cd040] headers: len: 118
HTTP/1.1 200 OK
Server: Logitech Media Server (7.9.1 - 1504317335)
Connection: close
Content-Type: audio/x-aiff
[21:04:02.741364] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.741388] sendRESP:188 [7]: RESP
[21:04:02.741446] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.741617] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.741689] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.741768] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.741842] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.741910] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.741994] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.742027] AVTSetPlayMode:167 [0x752480]: uPNP set play mode (cookie 0x4)
[21:04:02.742036] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.742107] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.742119] AVTPlay:151 [0x752480]: uPNP play (cookie 0x5)
[21:04:02.742205] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.742289] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.742397] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.742484] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.742587] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:02.742668] stream_thread:286 [0x6cd040] streambuf read 32767 bytes
[21:04:02.742815] ActionHandler:694 [0x752480]: Waited action <none>
[21:04:02.743579] ActionHandler:694 [0x752480]: Waited action PlayResponse
[21:04:02.778262] output_http_thread:143 [0x6cd040]: got HTTP connection 18
[21:04:02.840462] resample_newstream:247 [0x6cd040]: disable resampling - rates match 44100
[21:04:02.840549] process_newstream:121 [0x6cd040]: processing: inactive
[21:04:02.842815] stream_thread:286 [0x6cd040] streambuf read 1 bytes
[21:04:02.842992] stream_thread:286 [0x6cd040] streambuf read 524283 bytes
[21:04:02.878550] _output_new_stream:430 [0x6cd040]: PCM encoding r:44100 s:16 f:w
[21:04:02.878588] _output_new_stream:431 [0x6cd040]: HTTP -2, estimated len 31580228
[21:04:02.878609] output_http_thread:172 [0x6cd040]: drain is 2621440 (waited 183)
[21:04:02.878694] handle_http:467 [0x6cd040]: received GET /bridge-1.wav HTTP/1.1
[21:04:02.878708] handle_http:470 [0x6cd040]: HTTP headers
User-Agent: Lavf/58.13.100
Accept: */*
Range: bytes=0-
Connection: close
Host: 192.168.21.3:49153
Icy-MetaData: 1
[21:04:02.878739] handle_http:555 [0x6cd040]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/wav
Accept-Ranges: none
Content-Length: 31580228
[21:04:02.878749] _output_fill:187 [0x6cd040] PCM header sent (44 bytes)
[21:04:02.878757] gain_and_fade:923 [0x6cd040]: track start rate:44100 gain:0
[21:04:02.943131] stream_thread:286 [0x6cd040] streambuf read 5 bytes
[21:04:02.943332] stream_thread:286 [0x6cd040] streambuf read 524279 bytes
[21:04:03.023864] _SyncNotifState:555 JPLAY FEMTO: uPNP playing
[21:04:03.023899] sq_notify:580 [0x6cd040] play notification
[21:04:03.023942] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:03.024343] ActionHandler:750 [0x752480]: position 0 (cookie 0x6)
[21:04:03.024359] sq_notify:675 [0x6cd040] time 0 0
[21:04:03.024369] sq_notify:699 [0x6cd040] track 1 started at 2446135985
[21:04:03.024403] sendSTAT:160 [0x6cd040]: STAT:[STMs] msplayed 0
[21:04:03.043469] stream_thread:286 [0x6cd040] streambuf read 9 bytes
[21:04:03.043643] stream_thread:286 [0x6cd040] streambuf read 524275 bytes
[21:04:03.143732] stream_thread:286 [0x6cd040] streambuf read 13 bytes
[21:04:03.143923] stream_thread:286 [0x6cd040] streambuf read 524271 bytes
[21:04:03.244016] stream_thread:286 [0x6cd040] streambuf read 17 bytes
[21:04:03.244207] stream_thread:286 [0x6cd040] streambuf read 524267 bytes
[21:04:03.344295] stream_thread:286 [0x6cd040] streambuf read 21 bytes
[21:04:03.344498] stream_thread:286 [0x6cd040] streambuf read 524263 bytes
[21:04:03.444585] stream_thread:286 [0x6cd040] streambuf read 25 bytes
[21:04:03.444751] stream_thread:286 [0x6cd040] streambuf read 388067 bytes
[21:04:04.000300] process:491 [0x6cd040] strm
[21:04:04.000321] process:491 [0x6d12d8] strm
[21:04:04.000348] process_strm:232 [0x6cd040] strm command t
[21:04:04.000365] process_strm:232 [0x6d12d8] strm command t
[21:04:04.000380] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:04.000391] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:04:04.524442] ActionHandler:750 [0x752480]: position 0 (cookie 0x9)
[21:04:04.524483] sq_notify:675 [0x6cd040] time 0 0
[21:04:05.001460] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:05.525066] ActionHandler:750 [0x752480]: position 0 (cookie 0xb)
[21:04:05.525095] sq_notify:675 [0x6cd040] time 0 0
[21:04:06.002553] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:07.003647] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:07.025145] ActionHandler:750 [0x752480]: position 0 (cookie 0xe)
[21:04:07.025166] sq_notify:675 [0x6cd040] time 4001 0
[21:04:08.004744] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 4001
[21:04:08.525732] ActionHandler:750 [0x752480]: position 0 (cookie 0x10)
[21:04:08.525767] sq_notify:675 [0x6cd040] time 5502 0
[21:04:09.001223] process:491 [0x6cd040] strm
[21:04:09.001243] process:491 [0x6d12d8] strm
[21:04:09.001266] process_strm:232 [0x6cd040] strm command t
[21:04:09.001282] process_strm:232 [0x6d12d8] strm command t
[21:04:09.001296] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 5502
[21:04:09.001315] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:04:10.002370] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 5502
[21:04:10.026025] ActionHandler:750 [0x752480]: position 0 (cookie 0x13)
[21:04:10.026049] sq_notify:675 [0x6cd040] time 7002 0
[21:04:11.001478] UpdateThread:952 Presence checking
[21:04:11.003478] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 7002
[21:04:11.159255] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:04:11.163164] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:04:11.270142] UpdateThread:1014 [0x752480] UPnP keep alive: JPLAY FEMTO
[21:04:11.358993] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:04:11.361305] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:04:11.526594] ActionHandler:750 [0x752480]: position 0 (cookie 0x15)
[21:04:11.526621] sq_notify:675 [0x6cd040] time 8503 0
[21:04:12.004568] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 8503
[21:04:13.005669] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 8503
[21:04:13.026792] ActionHandler:750 [0x752480]: position 0 (cookie 0x18)
[21:04:13.026813] sq_notify:675 [0x6cd040] time 10003 0
[21:04:13.515986] UpdateThread:1014 [0x752480] UPnP keep alive: JPLAY FEMTO
[21:04:14.000927] slimproto_run:564 [0x6cd040] Closing CLI socket 9
[21:04:14.000955] process:491 [0x6d12d8] strm
[21:04:14.000981] process_strm:232 [0x6d12d8] strm command t
[21:04:14.000991] process:491 [0x6cd040] strm
[21:04:14.000993] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:04:14.001012] process_strm:232 [0x6cd040] strm command t
[21:04:14.001029] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 10003
[21:04:14.527491] ActionHandler:750 [0x752480]: position 0 (cookie 0x1a)
[21:04:14.527525] sq_notify:675 [0x6cd040] time 11504 0
[21:04:15.002068] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 11504
[21:04:16.003154] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 11504
[21:04:16.027590] ActionHandler:750 [0x752480]: position 0 (cookie 0x1d)
[21:04:16.027613] sq_notify:675 [0x6cd040] time 13004 0
[21:04:17.004255] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 13004
[21:04:17.027886] ActionHandler:750 [0x752480]: position 0 (cookie 0x1f)
[21:04:17.027907] sq_notify:675 [0x6cd040] time 14004 0
[21:04:17.746218] read_line:550 fd: 18 read error: 104 Connection reset by peer
[21:04:17.746268] http_parse:474 cannot read method
[21:04:17.746283] handle_http:462 [0x6cd040]: http parsing error (null)
[21:04:17.746290] output_http_thread:195 [0x6cd040]: HTTP close 18 (bytes 894768) (n:2 res:0)
[21:04:17.746311] output_http_thread:143 [0x6cd040]: got HTTP connection 9
[21:04:17.746398] handle_http:467 [0x6cd040]: received GET /bridge-1.wav HTTP/1.1
[21:04:17.746412] handle_http:470 [0x6cd040]: HTTP headers
User-Agent: Lavf/58.13.100
Accept: */*
Range: bytes=0-
Connection: close
Host: 192.168.21.3:49153
Icy-MetaData: 1
[21:04:17.746427] http_send:589 HTTP send() error:HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/wav
Accept-Ranges: none
Content-Length: 31580228
4294967295 (strlen=137)
[21:04:17.746433] handle_http:555 [0x6cd040]: responding:
(null)
[21:04:17.746442] read_line:555 disconnected on the other end 9
[21:04:17.746450] handle_http:462 [0x6cd040]: http parsing error (null)
[21:04:17.746468] output_http_thread:195 [0x6cd040]: HTTP close 9 (bytes 894768) (n:2 res:0)
[21:04:18.001228] process:491 [0x6cd040] strm
[21:04:18.001249] process:491 [0x6d12d8] strm
[21:04:18.001297] process_strm:232 [0x6d12d8] strm command t
[21:04:18.001312] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:04:18.001312] process_strm:232 [0x6cd040] strm command t
[21:04:18.001340] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 14004
[21:04:18.528296] _SyncNotifState:520 [0x752480]: uPNP stop
[21:04:18.528330] sq_notify:633 [0x6cd040] notify STOP
[21:04:18.528376] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 14004
[21:04:18.528423] sendSTAT:160 [0x6cd040]: STAT:[STMo] msplayed 14004
[21:04:18.528801] process:491 [0x6cd040] strm
[21:04:18.528822] process_strm:229 [0x6cd040] strm command p
[21:04:18.528831] process_strm:259 [0x6cd040] pause (interval:0)
[21:04:18.528841] AVTBasic:201 [0x752480]: uPNP Pause (cookie 0x23)
[21:04:18.528897] sendSTAT:160 [0x6cd040]: STAT:[STMp] msplayed 14004
[21:04:18.529542] ActionHandler:694 [0x752480]: Waited action PauseResponse
[21:04:18.655092] process:491 [0x6cd040] audg
[21:04:18.655125] process_audg:397 [0x6cd040] (old) audg gainL: 0 gainR: 0
[21:04:18.655136] CtrlSetMute:282 [0x752480]: uPNP mute 1 (cookie 0x24)
[21:04:18.655211] process:491 [0x6cd040] strm
[21:04:18.655220] process_strm:229 [0x6cd040] strm command u
[21:04:18.655226] process_strm:278 [0x6cd040] unpause at: 0 now: 2446151616
[21:04:18.655232] sq_set_time:327 [0x6cd040] time cmd bb:bb:6b:28:27:74 time -0.01
[21:04:18.655285] cli_open_socket:187 [0x6cd040]: opened CLI socket 9
[21:04:18.723014] sendSTAT:160 [0x6cd040]: STAT:[STMr] msplayed 14004
[21:04:18.723069] process:491 [0x6cd040] audg
[21:04:18.723080] process_audg:397 [0x6cd040] (old) audg gainL: 128 gainR: 128
[21:04:18.723088] CtrlSetMute:282 [0x752480]: uPNP mute 0 (cookie 0x25)
[21:04:18.723155] CtrlSetVolume:253 [0x752480]: uPNP volume 100 (cookie 0x26)
[21:04:18.723217] process:491 [0x6cd040] strm
[21:04:18.723224] process_strm:232 [0x6cd040] strm command q
[21:04:18.723230] decode_flush:206 [0x6cd040]: decode flush
[21:04:18.747934] shutdown_socket:383 closed socket 16
[21:04:18.747963] output_http_thread:375 [0x6cd040]: end thread 0 (894768 bytes)
[21:04:18.748300] output_flush:562 [0x6cd040]: flush output buffer
[21:04:18.748367] sendSTAT:160 [0x6cd040]: STAT:[STMf] msplayed 0
[21:04:18.748391] AVTStop:217 [0x752480]: uPNP stop (cookie 0x27)
[21:04:18.748450] process:491 [0x6cd040] audg
[21:04:18.748467] process_audg:397 [0x6cd040] (old) audg gainL: 128 gainR: 128
[21:04:18.748481] process:491 [0x6cd040] strm
[21:04:18.748487] process_strm:229 [0x6cd040] strm command s
[21:04:18.748493] process_strm:298 [0x6cd040], strm s autostart: 1 transition period: 10 transition type: 0 codec: p
[21:04:18.748499] sendSTAT:160 [0x6cd040]: STAT:[STMf] msplayed 0
[21:04:18.748514] process_strm:313 [0x6cd040]: set fade mode: 0
[21:04:18.749117] ActionHandler:694 [0x752480]: Waited action StopResponse
[21:04:18.751013] sq_get_metadata:501 [0x6cd040]: idx 0
artist:Céline Dion
album:1 fille & 4 types
title:01 - Tout l'or des hommes
genre:No Genre
duration:164.903
size:0
cover:http://192.168.21.3:9002/music/8ee4edf5/cover.jpg
[21:04:18.751046] codec_open:235 codec open: 'p'
[21:04:18.751066] bind_socket:358 cannot bind socket 16
[21:04:18.751075] bind_socket:367 socket binding 16 on port 49153
[21:04:18.751085] output_start:82 [0x6cd040]: start thread 0
[21:04:18.751109] sq_callback:310 [0x752480]:
artist:Céline Dion
album:1 fille & 4 types
title:01 - Tout l'or des hommes
genre:No Genre
duration:164.903
size:0
cover:
offset:0
[21:04:18.751120] sq_callback:337 [0x752480]: current URI set http://192.168.21.3:49153/bridge-2.wav
[21:04:18.751164] AVTSetURI:89 [0x752480]: DIDL header: <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/">
<item id="1" parentID="0" restricted="1">
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
<res duration="0:02:44.903" protocolInfo="http-get:*:audio/wav:DLNA.ORG_OP=00;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=09700000000000000000000000000000" sampleFrequency="44100" bitsPerSample="16" nrAudioChannels="2" size="29088889">http://192.168.21.3:49153/bridge-2.wav</res>
</item>
</DIDL-Lite>
[21:04:18.751171] AVTSetURI:91 [0x752480]: uPNP setURI http://192.168.21.3:49153/bridge-2.wav (cookie 0x28)
[21:04:18.751299] process_start:1184 [0x6cd040]: codec:p, ch:2, s:16, r:44100
[21:04:18.751311] stream_sock:397 [0x6cd040] connecting to 192.168.21.3:9002
[21:04:18.751364] stream_sock:426 [0x6cd040] header: GET /stream.mp3?player=bb:bb:6b:28:27:74 HTTP/1.0
[21:04:18.751379] sendSTAT:160 [0x6cd040]: STAT:[STMc] msplayed 0
[21:04:18.751402] process:491 [0x6cd040] audg
[21:04:18.751409] process_audg:397 [0x6cd040] (old) audg gainL: 128 gainR: 128
[21:04:18.752105] ActionHandler:694 [0x752480]: Waited action SetAVTransportURIResponse
[21:04:18.756002] stream_thread:190 [0x6cd040] headers: len: 118
HTTP/1.1 200 OK
Server: Logitech Media Server (7.9.1 - 1504317335)
Connection: close
Content-Type: audio/x-aiff
[21:04:18.756046] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756067] sendRESP:188 [7]: RESP
[21:04:18.756149] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756263] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756385] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756464] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756554] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756626] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756681] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756703] AVTSetPlayMode:167 [0x752480]: uPNP set play mode (cookie 0x29)
[21:04:18.756751] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756787] AVTPlay:151 [0x752480]: uPNP play (cookie 0x2a)
[21:04:18.756836] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.756929] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.757028] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.757113] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.757223] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.757310] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:18.757387] stream_thread:286 [0x6cd040] streambuf read 32767 bytes
[21:04:18.757530] ActionHandler:694 [0x752480]: Waited action <none>
[21:04:18.758259] ActionHandler:694 [0x752480]: Waited action PlayResponse
[21:04:18.792686] output_http_thread:143 [0x6cd040]: got HTTP connection 18
[21:04:18.855007] resample_newstream:247 [0x6cd040]: disable resampling - rates match 44100
[21:04:18.855048] process_newstream:121 [0x6cd040]: processing: inactive
[21:04:18.857530] stream_thread:286 [0x6cd040] streambuf read 1 bytes
[21:04:18.857751] stream_thread:286 [0x6cd040] streambuf read 524283 bytes
[21:04:18.892959] _output_new_stream:430 [0x6cd040]: PCM encoding r:44100 s:16 f:w
[21:04:18.893015] _output_new_stream:431 [0x6cd040]: HTTP -2, estimated len 29088932
[21:04:18.893025] output_http_thread:172 [0x6cd040]: drain is 2621440 (waited 142)
[21:04:18.893112] handle_http:467 [0x6cd040]: received GET /bridge-2.wav HTTP/1.1
[21:04:18.893124] handle_http:470 [0x6cd040]: HTTP headers
User-Agent: Lavf/58.13.100
Accept: */*
Range: bytes=0-
Connection: close
Host: 192.168.21.3:49153
Icy-MetaData: 1
[21:04:18.893162] handle_http:555 [0x6cd040]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/wav
Accept-Ranges: none
Content-Length: 29088932
[21:04:18.893172] _output_fill:187 [0x6cd040] PCM header sent (44 bytes)
[21:04:18.893179] gain_and_fade:923 [0x6cd040]: track start rate:44100 gain:0
[21:04:18.957898] stream_thread:286 [0x6cd040] streambuf read 5 bytes
[21:04:18.958104] stream_thread:286 [0x6cd040] streambuf read 524279 bytes
[21:04:19.058232] stream_thread:286 [0x6cd040] streambuf read 9 bytes
[21:04:19.058432] stream_thread:286 [0x6cd040] streambuf read 524275 bytes
[21:04:19.158523] stream_thread:286 [0x6cd040] streambuf read 13 bytes
[21:04:19.158711] stream_thread:286 [0x6cd040] streambuf read 524271 bytes
[21:04:19.258790] stream_thread:286 [0x6cd040] streambuf read 17 bytes
[21:04:19.258985] stream_thread:286 [0x6cd040] streambuf read 524267 bytes
[21:04:19.359067] stream_thread:286 [0x6cd040] streambuf read 21 bytes
[21:04:19.359283] stream_thread:286 [0x6cd040] streambuf read 524263 bytes
[21:04:19.459365] stream_thread:286 [0x6cd040] streambuf read 25 bytes
[21:04:19.459545] stream_thread:286 [0x6cd040] streambuf read 440987 bytes
[21:04:19.528191] _SyncNotifState:555 JPLAY FEMTO: uPNP playing
[21:04:19.528229] sq_notify:580 [0x6cd040] play notification
[21:04:20.028529] ActionHandler:750 [0x752480]: position 0 (cookie 0x2c)
[21:04:20.028565] sq_notify:675 [0x6cd040] time 0 0
[21:04:20.028576] sq_notify:699 [0x6cd040] track 2 started at 2446152990
[21:04:20.028592] sendSTAT:160 [0x6cd040]: STAT:[STMs] msplayed 0
[21:04:20.028631] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:21.029669] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:21.529125] ActionHandler:750 [0x752480]: position 0 (cookie 0x2e)
[21:04:21.529157] sq_notify:675 [0x6cd040] time 0 0
[21:04:22.030765] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:23.001171] process:491 [0x6cd040] strm
[21:04:23.001214] process_strm:232 [0x6cd040] strm command t
[21:04:23.001228] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:23.001260] process:491 [0x6d12d8] strm
[21:04:23.001283] process_strm:232 [0x6d12d8] strm command t
[21:04:23.001292] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:04:23.029322] ActionHandler:750 [0x752480]: position 0 (cookie 0x31)
[21:04:23.029345] sq_notify:675 [0x6cd040] time 0 0
[21:04:24.002292] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:24.029794] ActionHandler:750 [0x752480]: position 0 (cookie 0x33)
[21:04:24.029818] sq_notify:675 [0x6cd040] time 4001 0
[21:04:25.003417] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 4001
[21:04:25.530041] ActionHandler:750 [0x752480]: position 0 (cookie 0x36)
[21:04:25.530073] sq_notify:675 [0x6cd040] time 5501 0
[21:04:26.004534] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 5501
[21:04:27.005637] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 5501
[21:04:27.030535] ActionHandler:750 [0x752480]: position 0 (cookie 0x38)
[21:04:27.030557] sq_notify:675 [0x6cd040] time 7002 0
[21:04:28.000262] process:491 [0x6cd040] strm
[21:04:28.000304] process_strm:232 [0x6cd040] strm command t
[21:04:28. process:491 [0x6d12d8] strm
[21:04:28.000317] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 7002
[21:04:28.000339] process_strm:232 [0x6d12d8] strm command t
[21:04:28.000363] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:04:28.530925] ActionHandler:750 [0x752480]: position 0 (cookie 0x3b)
[21:04:28.530959] sq_notify:675 [0x6cd040] time 8502 0
[21:04:29.001397] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 8502
[21:04:30.002500] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 8502
[21:04:30.031396] ActionHandler:750 [0x752480]: position 0 (cookie 0x3d)
[21:04:30.031432] sq_notify:675 [0x6cd040] time 10002 0
[21:04:31.000474] UpdateThread:952 Presence checking
[21:04:31.003596] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 10002
[21:04:31.531856] ActionHandler:750 [0x752480]: position 0 (cookie 0x40)
[21:04:31.531898] sq_notify:675 [0x6cd040] time 11503 0
[21:04:31.740446] UpdateThread:1014 [0x752480] UPnP keep alive: JPLAY FEMTO
[21:04:32.000416] slimproto_run:564 [0x6cd040] Closing CLI socket 9
[21:04:32.000482] process:491 [0x6cd040] strm
[21:04:32.000489] process:491 [0x6d12d8] strm
[21:04:32.000494] process_strm:232 [0x6cd040] strm command t
[21:04:32.000531] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 11503
[21:04:32.000531] process_strm:232 [0x6d12d8] strm command t
[21:04:32.000600] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
[21:04:32.233507] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:04:32.434153] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:04:32.858173] UpdateThread:1014 [0x752480] UPnP keep alive: JPLAY FEMTO
[21:04:33.001575] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 11503
[21:04:33.032077] ActionHandler:750 [0x752480]: position 0 (cookie 0x43)
[21:04:33.032100] sq_notify:675 [0x6cd040] time 13003 0
[21:04:33.761947] read_line:550 fd: 18 read error: 104 Connection reset by peer
[21:04:33.762000] http_parse:474 cannot read method
[21:04:33.762008] handle_http:462 [0x6cd040]: http parsing error (null)
[21:04:33.762022] output_http_thread:195 [0x6cd040]: HTTP close 18 (bytes 947688) (n:2 res:0)
[21:04:33.762045] output_http_thread:143 [0x6cd040]: got HTTP connection 9
[21:04:33.762138] handle_http:467 [0x6cd040]: received GET /bridge-2.wav HTTP/1.1
[21:04:33.762152] handle_http:470 [0x6cd040]: HTTP headers
User-Agent: Lavf/58.13.100
Accept: */*
Range: bytes=0-
Connection: close
Host: 192.168.21.3:49153
Icy-MetaData: 1
[21:04:33.762167] http_send:589 HTTP send() error:HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/wav
Accept-Ranges: none
Content-Length: 29088932
4294967295 (strlen=137)
[21:04:33.762173] handle_http:555 [0x6cd040]: responding:
(null)
[21:04:33.762182] read_line:555 disconnected on the other end 9
[21:04:33.762190] handle_http:462 [0x6cd040]: http parsing error (null)
[21:04:33.762195] output_http_thread:195 [0x6cd040]: HTTP close 9 (bytes 947688) (n:2 res:0)
[21:04:34.002680] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 13003
[21:04:34.532437] ActionHandler:750 [0x752480]: position 0 (cookie 0x45)
[21:04:34.532478] sq_notify:675 [0x6cd040] time 14504 0
[21:04:34.532506] _SyncNotifState:520 [0x752480]: uPNP stop
[21:04:34.532527] sq_notify:633 [0x6cd040] notify STOP
[21:04:34.532545] sendSTAT:160 [0x6cd040]: STAT:[STMo] msplayed 14504
[21:04:34.532851] process:491 [0x6cd040] strm
[21:04:34.532862] process_strm:229 [0x6cd040] strm command p
[21:04:34.532868] process_strm:259 [0x6cd040] pause (interval:0)
[21:04:34.532875] AVTBasic:201 [0x752480]: uPNP Pause (cookie 0x47)
[21:04:34.532921] sendSTAT:160 [0x6cd040]: STAT:[STMp] msplayed 14504
[21:04:34.533704] ActionHandler:694 [0x752480]: Waited action PauseResponse
[21:04:34.658970] process:491 [0x6cd040] audg
[21:04:34.658993] process_audg:397 [0x6cd040] (old) audg gainL: 0 gainR: 0
[21:04:34.659004] CtrlSetMute:282 [0x752480]: uPNP mute 1 (cookie 0x48)
[21:04:34.659075] process:491 [0x6cd040] strm
[21:04:34.659085] process_strm:229 [0x6cd040] strm command u
[21:04:34.659091] process_strm:278 [0x6cd040] unpause at: 0 now: 2446167620
[21:04:34.659097] sq_set_time:327 [0x6cd040] time cmd bb:bb:6b:28:27:74 time -0.01
[21:04:34.659148] cli_open_socket:187 [0x6cd040]: opened CLI socket 9
[21:04:34.727208] sendSTAT:160 [0x6cd040]: STAT:[STMr] msplayed 14504
[21:04:34.727266] process:491 [0x6cd040] audg
[21:04:34.727277] process_audg:397 [0x6cd040] (old) audg gainL: 128 gainR: 128
[21:04:34.727284] CtrlSetMute:282 [0x752480]: uPNP mute 0 (cookie 0x49)
[21:04:34.727355] CtrlSetVolume:253 [0x752480]: uPNP volume 100 (cookie 0x4a)
[21:04:34.727438] process:491 [0x6cd040] strm
[21:04:34.727456] process_strm:232 [0x6cd040] strm command q
[21:04:34.727467] decode_flush:206 [0x6cd040]: decode flush
[21:04:34.763714] shutdown_socket:383 closed socket 16
[21:04:34.763740] output_http_thread:375 [0x6cd040]: end thread 0 (947688 bytes)
[21:04:34.764063] output_flush:562 [0x6cd040]: flush output buffer
[21:04:34.764133] sendSTAT:160 [0x6cd040]: STAT:[STMf] msplayed 0
[21:04:34.764155] AVTStop:217 [0x752480]: uPNP stop (cookie 0x4b)
[21:04:34.764212] process:491 [0x6cd040] audg
[21:04:34.764220] process_audg:397 [0x6cd040] (old) audg gainL: 128 gainR: 128
[21:04:34.764228] process:491 [0x6cd040] strm
[21:04:34.764234] process_strm:229 [0x6cd040] strm command s
[21:04:34.764239] process_strm:298 [0x6cd040], strm s autostart: 1 transition period: 10 transition type: 0 codec: p
[21:04:34.764244] sendSTAT:160 [0x6cd040]: STAT:[STMf] msplayed 0
[21:04:34.764260] process_strm:313 [0x6cd040]: set fade mode: 0
[21:04:34.764873] ActionHandler:694 [0x752480]: Waited action StopResponse
[21:04:34.766793] sq_get_metadata:501 [0x6cd040]: idx 0
artist:Céline Dion
album:1 fille & 4 types
title:01 - Tout l'or des hommes
genre:No Genre
duration:135.776
size:0
cover:http://192.168.21.3:9002/music/8ee4edf5/cover.jpg
[21:04:34.766829] codec_open:235 codec open: 'p'
[21:04:34.766850] bind_socket:358 cannot bind socket 16
[21:04:34.766859] bind_socket:367 socket binding 16 on port 49153
[21:04:34.766869] output_start:82 [0x6cd040]: start thread 0
[21:04:34.766891] sq_callback:310 [0x752480]:
artist:Céline Dion
album:1 fille & 4 types
title:01 - Tout l'or des hommes
genre:No Genre
duration:135.776
size:0
cover:
offset:0
[21:04:34.766907] sq_callback:337 [0x752480]: current URI set http://192.168.21.3:49153/bridge-3.wav
[21:04:34.766950] AVTSetURI:89 [0x752480]: DIDL header: <DIDL-Lite xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/">
<item id="1" parentID="0" restricted="1">
<upnp:class>object.item.audioItem.musicTrack</upnp:class>
<res duration="0:02:15.776" protocolInfo="http-get:*:audio/wav:DLNA.ORG_OP=00;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=09700000000000000000000000000000" sampleFrequency="44100" bitsPerSample="16" nrAudioChannels="2" size="23950886">http://192.168.21.3:49153/bridge-3.wav</res>
</item>
</DIDL-Lite>
[21:04:34.766956] AVTSetURI:91 [0x752480]: uPNP setURI http://192.168.21.3:49153/bridge-3.wav (cookie 0x4c)
[21:04:34.767085] process_start:1184 [0x6cd040]: codec:p, ch:2, s:16, r:44100
[21:04:34.767097] stream_sock:397 [0x6cd040] connecting to 192.168.21.3:9002
[21:04:34.767151] stream_sock:426 [0x6cd040] header: GET /stream.mp3?player=bb:bb:6b:28:27:74 HTTP/1.0
[21:04:34.767162] sendSTAT:160 [0x6cd040]: STAT:[STMc] msplayed 0
[21:04:34.767184] process:491 [0x6cd040] audg
[21:04:34.767192] process_audg:397 [0x6cd040] (old) audg gainL: 128 gainR: 128
[21:04:34.767954] ActionHandler:694 [0x752480]: Waited action SetAVTransportURIResponse
[21:04:34.770789] stream_thread:190 [0x6cd040] headers: len: 118
HTTP/1.1 200 OK
Server: Logitech Media Server (7.9.1 - 1504317335)
Connection: close
Content-Type: audio/x-aiff
[21:04:34.770841] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.770862] sendRESP:188 [7]: RESP
[21:04:34.770911] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771070] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771144] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771220] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771292] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771368] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771444] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771484] AVTSetPlayMode:167 [0x752480]: uPNP set play mode (cookie 0x4d)
[21:04:34.771493] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771570] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771572] AVTPlay:151 [0x752480]: uPNP play (cookie 0x4e)
[21:04:34.771673] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771752] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771835] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.771919] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.772009] stream_thread:286 [0x6cd040] streambuf read 32768 bytes
[21:04:34.772086] stream_thread:286 [0x6cd040] streambuf read 32767 bytes
[21:04:34.772448] ActionHandler:694 [0x752480]: Waited action <none>
[21:04:34.773171] ActionHandler:694 [0x752480]: Waited action PlayResponse
[21:04:34.807388] output_http_thread:143 [0x6cd040]: got HTTP connection 18
[21:04:34.869338] resample_newstream:247 [0x6cd040]: disable resampling - rates match 44100
[21:04:34.869392] process_newstream:121 [0x6cd040]: processing: inactive
[21:04:34.872207] stream_thread:286 [0x6cd040] streambuf read 1 bytes
[21:04:34.872383] stream_thread:286 [0x6cd040] streambuf read 524283 bytes
[21:04:34.907638] _output_new_stream:430 [0x6cd040]: PCM encoding r:44100 s:16 f:w
[21:04:34.907675] _output_new_stream:431 [0x6cd040]: HTTP -2, estimated len 23950928
[21:04:34.907688] output_http_thread:172 [0x6cd040]: drain is 2621440 (waited 141)
[21:04:34.907786] handle_http:467 [0x6cd040]: received GET /bridge-3.wav HTTP/1.1
[21:04:34.907800] handle_http:470 [0x6cd040]: HTTP headers
User-Agent: Lavf/58.13.100
Accept: */*
Range: bytes=0-
Connection: close
Host: 192.168.21.3:49153
Icy-MetaData: 1
[21:04:34.907831] handle_http:555 [0x6cd040]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/wav
Accept-Ranges: none
Content-Length: 23950928
[21:04:34.907846] _output_fill:187 [0x6cd040] PCM header sent (44 bytes)
[21:04:34.907854] gain_and_fade:923 [0x6cd040]: track start rate:44100 gain:0
[21:04:34.972527] stream_thread:286 [0x6cd040] streambuf read 5 bytes
[21:04:34.972731] stream_thread:286 [0x6cd040] streambuf read 524279 bytes
[21:04:35.032239] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:04:35.072861] stream_thread:286 [0x6cd040] streambuf read 9 bytes
[21:04:35.073047] stream_thread:286 [0x6cd040] streambuf read 524275 bytes
[21:04:35.173136] stream_thread:286 [0x6cd040] streambuf read 13 bytes
[21:04:35.173326] stream_thread:286 [0x6cd040] streambuf read 524271 bytes
[21:04:35.232497] UpdateThread:1014 [0x754688] UPnP keep alive: [LG] webOS TV
[21:04:35.273407] stream_thread:286 [0x6cd040] streambuf read 17 bytes
[21:04:35.273606] stream_thread:286 [0x6cd040] streambuf read 524267 bytes
[21:04:35.373696] stream_thread:286 [0x6cd040] streambuf read 21 bytes
[21:04:35.373889] stream_thread:286 [0x6cd040] streambuf read 524263 bytes
[21:04:35.473978] stream_thread:286 [0x6cd040] streambuf read 25 bytes
[21:04:35.474149] stream_thread:286 [0x6cd040] streambuf read 423347 bytes
[21:04:35.772647] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:35.893815] _SyncNotifState:555 JPLAY FEMTO: uPNP playing
[21:04:35.893848] sq_notify:580 [0x6cd040] play notification
[21:04:35.894251] ActionHandler:750 [0x752480]: position 0 (cookie 0x4f)
[21:04:35.894264] sq_notify:675 [0x6cd040] time 0 0
[21:04:35.894277] sq_notify:699 [0x6cd040] track 3 started at 2446168855
[21:04:35.894316] sendSTAT:160 [0x6cd040]: STAT:[STMs] msplayed 0
[21:04:36.000251] process:491 [0x6cd040] strm
[21:04:36.000279] process_strm:232 [0x6cd040] strm command t
[21:04:36.000293] sendSTAT:160 [0x6cd040]: STAT:[STMt] msplayed 0
[21:04:36.000343] process:491 [0x6d12d8] strm
[21:04:36.000366] process_strm:232 [0x6d12d8] strm command t
[21:04:36.000375] sendSTAT:160 [0x6d12d8]: STAT:[STMt] msplayed 0
... même avec une ancienne version j'ai le soucis : le problème est ailleurs pour Roon...