Le Forum Indépendant de la Hifi et des Audiophiles

Version complète : Roon
Vous consultez actuellement la version basse qualité d’un document. Voir la version complète avec le bon formatage.
Pages : 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 100 101 102 103 104 105 106 107 108 109 110 111 112 113 114 115 116 117 118 119 120 121 122 123 124 125 126 127 128 129 130 131 132 133 134 135 136 137 138 139 140 141 142 143 144 145 146 147 148 149 150 151 152 153 154 155 156 157 158 159 160 161 162 163 164 165 166 167 168 169 170 171 172 173 174 175 176 177 178 179 180 181 182 183 184 185 186 187 188 189 190 191 192 193 194 195 196 197 198 199 200 201 202 203 204 205 206 207 208 209 210 211 212 213 214 215 216 217 218 219 220 221 222 223 224 225 226 227 228 229 230 231 232 233 234 235 236 237 238 239 240 241 242 243 244 245 246 247 248 249 250 251 252 253 254 255 256 257 258 259 260 261 262 263 264 265 266 267 268 269 270 271 272 273 274 275 276 277 278 279 280 281 282 283 284 285 286 287 288 289 290 291 292 293 294 295 296 297 298 299 300 301 302 303 304 305 306 307 308 309 310 311 312 313 314 315 316 317 318 319 320 321 322 323 324 325 326 327 328 329 330 331 332 333 334 335 336 337 338 339 340 341 342 343 344 345 346 347 348 349 350 351 352 353 354 355 356 357 358 359 360 361 362 363 364 365 366 367 368 369 370 371 372 373 374 375 376 377 378 379 380 381 382 383 384 385 386 387 388 389 390 391 392 393 394 395 396 397 398 399 400 401 402 403 404 405 406 407 408 409 410 411 412 413 414 415 416 417 418 419 420 421 422 423 424 425 426 427 428 429 430 431 432 433 434 435 436 437 438 439 440 441 442 443 444 445 446 447 448 449 450 451 452 453 454 455 456 457 458 459 460 461 462 463 464 465 466 467 468 469 470 471 472 473 474 475 476 477 478 479 480 481 482 483 484 485 486 487 488 489 490 491 492 493 494 495 496 497 498 499 500 501 502 503 504 505 506 507 508 509 510 511 512 513 514 515 516 517 518 519 520 521 522 523 524 525 526 527 528 529 530 531 532 533 534 535 536 537 538 539 540 541 542 543 544 545 546 547 548 549 550 551 552 553 554 555 556 557 558 559 560 561 562 563 564 565 566 567 568 569 570 571 572 573 574 575 576 577 578 579 580 581 582 583 584 585 586 587 588 589 590 591 592 593 594 595 596 597 598 599 600 601 602 603 604 605 606 607 608 609 610 611 612 613 614 615 616 617 618 619 620 621 622 623 624 625 626 627 628 629 630 631 632 633 634 635
Pour faire simple Big Grin  ce serait une histoire de sample rate et de bit depth qui ne serait pas envoyé comme JPLAYFEMTO le voudrait... Les mystères de l'uPnP quoi....
Squeeze2upnp envoie ces infos uniquement dans le res:protocolinfo alors que JPLAY les attend dans les métadonnées.

Littlescarabee ou ceux qui voudraient essayer Roon + JPLAYFEMTO via Squeeze2upnp, par exemple, essayez la dernière version squeeze2upnp 1.8.2.2 pour voir...Wink
Merci LeDom pour tous ces efforts et améliorations de Squeeze2upnp.
Du coup, ça te paraît maintenant simple à configurer ?
Je suis sûr que la communauté Roon apprécierait un petit tutoriel mis à jour.
Au besoin je peux aider pour la mise en forme et/ou la traduction en anglais.
(02-05-2019, 11:09 AM)Le dom a écrit : [ -> ]Pour faire simple Big Grin  ce serait une histoire de sample rate et de bit depth qui ne serait pas envoyé comme JPLAYFEMTO le voudrait... Les mystères de l'uPnP quoi....
Squeeze2upnp envoie ces infos uniquement dans le res:protocolinfo alors que JPLAY les attend dans les métadonnées.

Littlescarabee ou ceux qui voudraient essayer Roon + JPLAYFEMTO via Squeeze2upnp, par exemple, essayez la dernière version squeeze2upnp 1.8.2.2 pour voir...Wink

Hello

Merci pour ton retour.
J'ai fait trois tests sur la 1.8.2.2 :
1) LMS + Squeeze2UPnP en plugins = KO ;  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

2) LMS + Squeeze2UPnP sur mon PC audio = KO
[Image: Capture-d-e-cran-2019-02-05-a-20-53-52.png]

3) Roon + Squeeze2UPnP sur mon PC audio = KO ...Roon ne voit plus mon Squeeze2UPnP  Huh ... même avec une ancienne version j'ai le soucis : le problème est ailleurs pour Roon...
(02-05-2019, 02:56 PM)alec_eiffel a écrit : [ -> ]Merci LeDom pour tous ces efforts et améliorations de Squeeze2upnp.
Du coup, ça te paraît maintenant simple à configurer ?
Je suis sûr que la communauté Roon apprécierait un petit tutoriel mis à jour.
Au besoin je peux aider pour la mise en forme et/ou la traduction en anglais.

Ce n'est pas très difficile à configurer mais pour ce qui est du petit tuto il faut que j'y réfléchisse et que je trouve le temps.

En ce qui concerne la config de squeeze2upnp elle est déjà très expliquée dans le package upnpbridge. 
Il suffit de lancer juste de lancer squeeze2upnp qui est dans le dossier bin, d'activer Squeezebox dans les paramètres de configuration de Roon.
Ce soft permet d'économiser les 230$ de l'interface uPnP Sonore et par la même occasion évite l'ajout d'un boîtier supplémentaire.  Wink

(02-05-2019, 10:11 PM)LittleScarabee a écrit : [ -> ]
(02-05-2019, 11:09 AM)Le dom a écrit : [ -> ]Pour faire simple Big Grin  ce serait une histoire de sample rate et de bit depth qui ne serait pas envoyé comme JPLAYFEMTO le voudrait... Les mystères de l'uPnP quoi....
Squeeze2upnp envoie ces infos uniquement dans le res:protocolinfo alors que JPLAY les attend dans les métadonnées.

Littlescarabee ou ceux qui voudraient essayer Roon + JPLAYFEMTO via Squeeze2upnp, par exemple, essayez la dernière version squeeze2upnp 1.8.2.2 pour voir...Wink

Hello

Merci pour ton retour.
J'ai fait trois tests sur la 1.8.2.2 :
1) LMS + Squeeze2UPnP en plugins = KO ;  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

2) LMS + Squeeze2UPnP sur mon PC audio = KO
[Image: Capture-d-e-cran-2019-02-05-a-20-53-52.png]

3) Roon + Squeeze2UPnP sur mon PC audio = KO ...Roon ne voit plus mon Squeeze2UPnP  Huh ... même avec une ancienne version j'ai le soucis : le problème est ailleurs pour Roon...

Bizarre, chez moi ça fonctionne parfaitement maintenant avec Roon et JPLAYFEMTO avec la version 1.8.2.2.

Roon ne voit peut être pas squeeze2upnp car tu as peut être toujours LMS qui tourne. Il ne faut pas d'autre serveurs LMS en ligne si tu veux que Roon détecte squeeze2upnp.
Je viens de nouveau de faire un test car j'avais lancé les process en Administrateur...en mode normal même combat : Roon ne voit plus personne (j'ai activé la compatibilité Roon pourtant...) As-tu modifiés des pamètres spécifiques dans le fichier ?
Content de savoir que pour toi ça fonctionne, quand je vois les log défilés il y a clairement plus de choses maintenant : il a grandement fait évoluer le binaire !
A part activer le mode Roon dans le fichier config.xml de squeeze2upnp, je n'ai rien fait d'autre avec la dernière mouture de Philippe.
Ah si j'ai mis le paramètre <sample_rate> à 192000.
Je me demande tout de même si dans ton cas ce n'est pas à cause de l'ASIO de ton DAC qui ne s'est pas installé que ça pose problème car JPLAY ou JPLAYFEMTO ont besoin du réglages des paramètres ASIO (Latency, ASIO buffer) pour jouer sur les possibilités et les performances de paramètre DAC Link dans les settings de JPLAY.

Est-ce que tu peux lancer un log de debug dans squeeze2upnp?
Roon et Squeeze2upnp sont bien sur même réseau? 192.168.21.xxx dans ton cas?
Vérifies que tu n'as absolument aucune autre instance genre LMS qui est lancée quelque part sur ton réseau. C'est impératif.

squeeze2upnp-win.exe -d all:info -f log.txt et le poster ou me l'envoyer par MP?
Pour ceux qui veulent implémenter une ou plusieurs courbes cibles rapidement dans Roon, voir ici https://community.roonlabs.com/t/room-cu...-dsp/61073

Il y a un petit calculateur et ensuite il suffit de recopier les fréquences, gain et Q dans l'égaliseur paramétrique, et sauvegarder éventuellement le préréglage.
Pas mal,

merci pour l'info alec_eiffel.
Suite à la sortie de la nouvelle offre Qobuz sur Roon, ces derniers m'ont envoyé un code d'activation pour un essai de 5 jours.
Après avoir rentré mes identifiants Qobuz dans Roon, il y a-t'il autre chose à faire ? Car au lancement de la lecture j'ai un message "arrêt de la lecture, Le morceau n'a pas pu être chargé"...
Puis "trop d'échecs, arrêt de la lecture.

Merci de votre aide, j'ai deux furieux qui arrivent pour passer la journée à la maison.
Pas assez de débit ?
Pages : 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 98 99 100 101 102 103 104 105 106 107 108 109 110 111 112 113 114 115 116 117 118 119 120 121 122 123 124 125 126 127 128 129 130 131 132 133 134 135 136 137 138 139 140 141 142 143 144 145 146 147 148 149 150 151 152 153 154 155 156 157 158 159 160 161 162 163 164 165 166 167 168 169 170 171 172 173 174 175 176 177 178 179 180 181 182 183 184 185 186 187 188 189 190 191 192 193 194 195 196 197 198 199 200 201 202 203 204 205 206 207 208 209 210 211 212 213 214 215 216 217 218 219 220 221 222 223 224 225 226 227 228 229 230 231 232 233 234 235 236 237 238 239 240 241 242 243 244 245 246 247 248 249 250 251 252 253 254 255 256 257 258 259 260 261 262 263 264 265 266 267 268 269 270 271 272 273 274 275 276 277 278 279 280 281 282 283 284 285 286 287 288 289 290 291 292 293 294 295 296 297 298 299 300 301 302 303 304 305 306 307 308 309 310 311 312 313 314 315 316 317 318 319 320 321 322 323 324 325 326 327 328 329 330 331 332 333 334 335 336 337 338 339 340 341 342 343 344 345 346 347 348 349 350 351 352 353 354 355 356 357 358 359 360 361 362 363 364 365 366 367 368 369 370 371 372 373 374 375 376 377 378 379 380 381 382 383 384 385 386 387 388 389 390 391 392 393 394 395 396 397 398 399 400 401 402 403 404 405 406 407 408 409 410 411 412 413 414 415 416 417 418 419 420 421 422 423 424 425 426 427 428 429 430 431 432 433 434 435 436 437 438 439 440 441 442 443 444 445 446 447 448 449 450 451 452 453 454 455 456 457 458 459 460 461 462 463 464 465 466 467 468 469 470 471 472 473 474 475 476 477 478 479 480 481 482 483 484 485 486 487 488 489 490 491 492 493 494 495 496 497 498 499 500 501 502 503 504 505 506 507 508 509 510 511 512 513 514 515 516 517 518 519 520 521 522 523 524 525 526 527 528 529 530 531 532 533 534 535 536 537 538 539 540 541 542 543 544 545 546 547 548 549 550 551 552 553 554 555 556 557 558 559 560 561 562 563 564 565 566 567 568 569 570 571 572 573 574 575 576 577 578 579 580 581 582 583 584 585 586 587 588 589 590 591 592 593 594 595 596 597 598 599 600 601 602 603 604 605 606 607 608 609 610 611 612 613 614 615 616 617 618 619 620 621 622 623 624 625 626 627 628 629 630 631 632 633 634 635