philippe44/LMS-Raop

Retarting Squeeze2raop after crash

Closed this issue · 5 comments

x-sven commented

Hey, after upgrading the lovely LMS Plugin to Version 4.42.0 with my docker container, it keeps constantly crashing. However, I'm not sure, if the update is the reason. Please find the log beginning from a restart. I started playing a online stream on a airplay device. (Does'nt matter which airplay device I select)

`Retarting Squeeze2raop after crash: /mnt/state/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-x86-64-static -Z -I -f /mnt/state/logs/raopbridge.log -d all=debug -x /mnt/state/prefs/raopbridge.xml
[06:48:48.596642] main:1535 Starting squeeze2raop version: v0.4.42.0 (Oct 30 2020 @ 17:45:49)

[06:48:48.596714] register_pcm:306 using pcm to decode aif,pcm
[06:48:48.596726] register_mad:429 using mad to decode mp3
[06:48:48.596735] register_alac:542 using alac to decode alc
[06:48:48.596744] register_flac:308 using flac to decode flc
[06:48:48.596762] register_faad:648 using faad to decode aac
[06:48:48.596771] register_vorbis:339 using vorbis to decode ogg
[06:48:48.596789] register_opus:282 using opus to decode ops
[06:48:48.596798] register_soxr:391 using soxr for resampling
[06:48:48.596941] Start:1282 Binding to 192.168.200.249
[06:48:48.597045] StartActiveRemote:1170 DACP port: 53527
[06:48:48.597271] mDNSsearchCallback:692 Updating configuration /mnt/state/prefs/raopbridge.xml
[06:48:48.714051] AddRaopDevice:816 [0xbd1c00]: AirPort Express
[06:48:48.714161] AddRaopDevice:866 [0xbd1c00]: adding renderer (EinWirelessAirPort)
[06:48:48.714824] raopcl_create:728 [0x7fc4500092e0]: using PCM coding
[06:48:48.715094] stream_thread_init:464 [0xb786c0] streambuf size: 2097152
[06:48:48.716632] output_raop_thread_init:126 [0xb786c0]: init output raop
[06:48:48.716666] output_init_common:289 [0x1aeaa0]: outputbuf size: 8953952
[06:48:48.716698] output_init_common:293 allocating 1764000
[06:48:48.716793] decode_thread_init:158 [0xb786c0]: init decode
[06:48:48.716918] resample_init:342 [0xb786c0]: resampling sync recipe: 0x00, flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000, stopband_begin: 0.00000, phase_response: -1.0
[06:48:48.717059] mDNSsearchCallback:692 Updating configuration /mnt/state/prefs/raopbridge.xml
[06:48:48.717103] discover_server:783 [0xb786c0] sending discovery
[06:48:48.717645] discover_server:815 [0xb786c0] got response from: 192.168.200.249:3483
[06:48:48.717686] slimproto:835 squeezelite [0xb786c0] <=> player [0xbd1c00]
[06:48:48.717708] slimproto:836 [0xb786c0] connecting to 192.168.200.249:3483
[06:48:48.717827] slimproto:866 [0xb786c0] connected
[06:48:48.717852] sendHELO:117 [0xb786c0] mac: aa:aa:5e:3c:cd:98
[06:48:48.717870] sendHELO:118 [0xb786c0] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3
[06:48:48.719399] process:540 [0xb786c0] strm
[06:48:48.719440] process_strm:244 [0xb786c0] strm command q
[06:48:48.719460] decode_flush:198 [0xb786c0]: decode flush
[06:48:48.719476] output_flush:334 [0xb786c0]: flush output buffer
[06:48:48.719493] sq_callback:277 [0xbd1c00]: device off or not controlled by LMS
[06:48:48.724158] process:540 [0xb786c0] audg
[06:48:48.724231] process_audg:437 [0xb786c0] (old) audg gainL: 80 gainR: 80
[06:48:48.724250] sq_callback:277 [0xbd1c00]: device off or not controlled by LMS
[06:48:48.726293] process:540 [0xb786c0] strm
[06:48:48.726336] process_strm:241 [0xb786c0] strm command s
[06:48:48.726356] process_strm:316 [0xb786c0], strm s autostart: 3 transition period: 10 transition type: 0 codec: m
[06:48:48.726375] sendSTAT:142 [0xb786c0]: ms_played: 0
[06:48:48.726390] sendSTAT:166 [0xb786c0]: STAT: STMf
[06:48:48.726428] process_strm:332 [0xb786c0]: set fade mode: 0
[06:48:48.726446] codec_open:227 codec open: 'm'
[06:48:48.726519] process_strm:348 [0xb786c0]: codec:m, ch:255, s:255, r:255
[06:48:48.726537] sq_callback:277 [0xbd1c00]: device off or not controlled by LMS
[06:48:48.726570] connect_socket:154 [0xb786c0] connecting to 212.122.148.181:80
[06:48:48.737064] AddRaopDevice:866 [0xbd29d0]: adding renderer (BoseSoundLinkAir)
[06:48:48.737580] raopcl_create:728 [0x7fc450011a90]: using PCM coding
[06:48:48.737713] stream_thread_init:464 [0xb7b1a8] streambuf size: 2097152
[06:48:48.738711] output_raop_thread_init:126 [0xb7b1a8]: init output raop
[06:48:48.738729] output_init_common:289 [0x1aeaa0]: outputbuf size: 8953952
[06:48:48.738738] output_init_common:293 allocating 1764000
[06:48:48.738777] decode_thread_init:158 [0xb7b1a8]: init decode
[06:48:48.738816] resample_init:342 [0xb7b1a8]: resampling sync recipe: 0x00, flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000, stopband_begin: 0.00000, phase_response: -1.0
[06:48:48.738888] mDNSsearchCallback:692 Updating configuration /mnt/state/prefs/raopbridge.xml
[06:48:48.739149] discover_server:783 [0xb7b1a8] sending discovery
[06:48:48.739503] discover_server:815 [0xb7b1a8] got response from: 192.168.200.249:3483
[06:48:48.739543] slimproto:835 squeezelite [0xb7b1a8] <=> player [0xbd29d0]
[06:48:48.739557] slimproto:836 [0xb7b1a8] connecting to 192.168.200.249:3483
[06:48:48.739635] slimproto:866 [0xb7b1a8] connected
[06:48:48.739649] sendHELO:117 [0xb7b1a8] mac: aa:aa:8a:5a:0e:03
[06:48:48.739659] sendHELO:118 [0xb7b1a8] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3
[06:48:48.740325] process:540 [0xb7b1a8] setd
[06:48:48.740358] sendSETDName:229 [14]: set playername: BoseSoundLinkAir
[06:48:48.740394] process:540 [0xb7b1a8] setd
[06:48:48.740480] process:540 [0xb7b1a8] aude
[06:48:48.740495] process_aude:420 [0xb7b1a8] on/off using aude 0
[06:48:48.740514] sq_callback:273 [0xbd29d0]: device set on/off 0
[06:48:48.740527] sq_callback:277 [0xbd29d0]: device off or not controlled by LMS
[06:48:48.740547] PlayerThread:409 [0xbd29d0]: tick 3652537188
[06:48:48.740598] PlayerThread:551 [0xbd29d0]: processing off
[06:48:48.740561] process:540 [0xb7b1a8] audg
[06:48:48.740624] process_audg:437 [0xb7b1a8] (old) audg gainL: 12 gainR: 12
[06:48:48.740637] sq_callback:277 [0xbd29d0]: device off or not controlled by LMS
[06:48:48.743049] stream_sock:587 [0xb786c0] header: GET /rbb/fritz/live/mp3/mid?_art=dj0yJmlwPTg5LjE4My4xMjMuNDAmaWQ9aWNzY3hsLTJ4NWtjdHJuYiZ0PTE2MDQzODYxMjQmcz03ODY2ZjI5YyM1OTE2OTNhZTgzNzc5MzE2MmM2ZjUwYjQ0NmMwZTNhZg HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: /
Host: rbb-edge-306f-dus-ts-cdn.cast.addradio.de
User-Agent: iTunes/4.7.1 (Linux; N; Debian; x86_64-linux; EN; utf8) SqueezeCenter, Squeezebox Server, Logitech Media Server/7.9.2/1578996832
Icy-Metadata: 1

[06:48:48.743077] sendSTAT:142 [0xb786c0]: ms_played: 0
[06:48:48.743091] sendSTAT:166 [0xb786c0]: STAT: STMc
[06:48:48.743128] process:540 [0xb786c0] audg
[06:48:48.743141] process_audg:437 [0xb786c0] (old) audg gainL: 80 gainR: 80
[06:48:48.743151] sq_callback:277 [0xbd1c00]: device off or not controlled by LMS
[06:48:48.743168] process:540 [0xb786c0] setd
[06:48:48.743177] sendSETDName:229 [10]: set playername: EinWirelessAirPort
[06:48:48.743199] process:540 [0xb786c0] setd
[06:48:48.743217] process:540 [0xb786c0] aude
[06:48:48.743236] process_aude:420 [0xb786c0] on/off using aude 1
[06:48:48.743246] sq_callback:273 [0xbd1c00]: device set on/off 1
[06:48:48.743264] process:540 [0xb786c0] audg
[06:48:48.743273] process_audg:437 [0xb786c0] (old) audg gainL: 80 gainR: 80
[06:48:48.743301] PlayerThread:409 [0xbd1c00]: tick 3652537191
[06:48:48.743330] PlayerThread:558 [0xbd1c00]: processing volume: 72 (-8.40)
[06:48:48.850465] process:540 [0xb786c0] strm
[06:48:48.850557] process_strm:244 [0xb786c0] strm command t
[06:48:48.850590] sendSTAT:142 [0xb786c0]: ms_played: 0
[06:48:48.850613] sendSTAT:166 [0xb786c0]: STAT: STMt
[06:48:48.853420] stream_thread:337 [0xb786c0] headers: len: 477
HTTP/1.0 200 OK
Content-Type: audio/mpeg
Date: Mon, 02 Nov 2020 06:48:48 GMT
icy-description:It's Fritz!
icy-name:Fritz
icy-pub:0
Server: dg-rbb-http_dus-ts_edge_e11f8aac55b4584ad15a4867f7894b15
Cache-Control: no-cache, no-store
Access-Control-Allow-Origin: *
Access-Control-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type
Access-Control-Allow-Methods: GET, OPTIONS, HEAD
Connection: Close
Expires: Mon, 26 Jul 1997 05:00:00 GMT
icy-metaint:16000

[06:48:48.853567] sendRESP:199 [10]: RESP
[06:48:48.855469] process:540 [0xb786c0] cont
[06:48:48.855499] process_cont:380 cont metaint: 16000 loop: 0
[06:48:48.953636] stream_thread:436 [0xb786c0] streambuf read 16000 bytes
[06:48:48.953723] stream_thread:390 [0xb786c0] icy meta: len: 48
StreamTitle='Sam Smith - Another One';
[06:48:48.953760] stream_thread:436 [0xb786c0] streambuf read 16000 bytes
[06:48:48.953802] sendMETA:213 [10]: META
[06:48:48.953821] stream_thread:436 [0xb786c0] streambuf read 16000 bytes
[06:48:48.953893] stream_thread:436 [0xb786c0] streambuf read 6192 bytes
[06:48:48.965170] stream_thread:436 [0xb786c0] streambuf read 2880 bytes
[06:48:48.965238] stream_thread:436 [0xb786c0] streambuf read 1440 bytes
[06:48:48.965322] stream_thread:436 [0xb786c0] streambuf read 2880 bytes
[06:48:48.965366] stream_thread:436 [0xb786c0] streambuf read 1440 bytes
[06:48:48.965419] stream_thread:436 [0xb786c0] streambuf read 1168 bytes
[06:48:48.965487] stream_thread:436 [0xb786c0] streambuf read 271 bytes

Retarting Squeeze2raop after crash: /mnt/state/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-x86-64 -Z -I -x /mnt/state/prefs/raopbridge.xml`

cribskip commented

Same on my raspberry pi (dunno where the CONFIG FILE ERROR comes from as it parses it appearently correctly)
It worked a few months ago just fine :-( I'll turn off auto-update ;-)

`
Starting Squeeze2raop: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-armv6hf-static -Z -I -b 10.0.0.2 -f /var/log/squeezeboxserver/raopbridge.log -d all=debug -x /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:32:06.339757] main:1535 Starting squeeze2raop version: v0.4.42.0 (Oct 30 2020 @ 17:44:40)

[12:32:06.340067] register_pcm:306 using pcm to decode aif,pcm
[12:32:06.340154] register_mad:429 using mad to decode mp3
[12:32:06.340193] register_alac:542 using alac to decode alc
[12:32:06.340227] register_flac:308 using flac to decode flc
[12:32:06.340260] register_faad:648 using faad to decode aac
[12:32:06.340292] register_vorbis:339 using vorbis to decode ogg
[12:32:06.340326] register_opus:282 using opus to decode ops
[12:32:06.340358] register_soxr:391 using soxr for resampling
[12:32:06.341583] Start:1282 Binding to 10.0.0.2
[12:32:06.341955] StartActiveRemote:1170 DACP port: 47999
[12:32:06.462502] AddRaopDevice:866 [0x3d5140]: adding renderer (am335x-opt)
[12:32:06.464306] raopcl_create:728 [0x76608840]: using PCM coding
[12:32:06.464835] stream_thread_init:464 [0x37dd18] streambuf size: 2097152
[12:32:06.469004] output_raop_thread_init:126 [0x37dd18]: init output raop
[12:32:06.469050] output_init_common:289 [0x1aeaa0]: outputbuf size: 0
[12:32:06.469070] output_init_common:293 allocating 1764000
[12:32:06.469146] decode_thread_init:158 [0x37dd18]: init decode
[12:32:06.469213] resample_init:342 [0x37dd18]: resampling sync recipe: 0x00, flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000, stopband_begin: 0.00000, phase_response: -1.0
[12:32:06.469328] mDNSsearchCallback:692 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:32:06.469359] discover_server:783 [0x37dd18] sending discovery
[12:32:06.470085] discover_server:815 [0x37dd18] got response from: 10.0.0.2:3483
[12:32:06.470151] slimproto:835 squeezelite [0x37dd18] <=> player [0x3d5140]
[12:32:06.470171] slimproto:836 [0x37dd18] connecting to 10.0.0.2:3483
[12:32:06.470371] slimproto:866 [0x37dd18] connected
[12:32:06.470400] sendHELO:117 [0x37dd18] mac: aa:aa:b0:e6:4f:a7
[12:32:06.470422] sendHELO:118 [0x37dd18] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3
[12:32:06.472744] process:540 [0x37dd18] strm
[12:32:06.472823] process_strm:244 [0x37dd18] strm command q
[12:32:06.472844] decode_flush:198 [0x37dd18]: decode flush
[12:32:06.472862] output_flush:334 [0x37dd18]: flush output buffer
[12:32:06.472884] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:32:06.481613] process:540 [0x37dd18] audg
[12:32:06.481698] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:32:06.481725] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:32:06.486832] process:540 [0x37dd18] strm
[12:32:06.486910] process_strm:241 [0x37dd18] strm command s
[12:32:06.486930] process_strm:316 [0x37dd18], strm s autostart: 3 transition period: 10 transition type: 0 codec: m
[12:32:06.486953] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:32:06.486970] sendSTAT:166 [0x37dd18]: STAT: STMf
[12:32:06.487019] process_strm:332 [0x37dd18]: set fade mode: 0
[12:32:06.487043] codec_open:227 codec open: 'm'
[12:32:06.487234] process_strm:348 [0x37dd18]: codec:m, ch:255, s:255, r:255
[12:32:06.487261] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:32:06.487325] connect_socket:154 [0x37dd18] connecting to 212.122.148.114:80
[12:32:06.566437] stream_sock:587 [0x37dd18] header: GET /wdr/1live/live/mp3/128/stream.mp3?ar-distributor=f0a1&_art=dj0yJmlwPTc5LjIwMy4xNTQuMTQzJmlkPWljc2N4bC14MmNzc3lsbGImdD0xNjA0OTE1NzYwJnM9Nzg2NmYyOWMjNmQ5NGJhOTU3MzAyYzlhY2QxZjc3ODA2ODYwOGI3MzE HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: /
Host: wdr-edge-300e-dus-ts-cdn.cast.addradio.de
User-Agent: iTunes/4.7.1 (Linux; N; Debian; armv7l-linux; DE; utf8) SqueezeCenter, Squeezebox Server, Logitech Media Server/7.9.4/1600144323
Icy-Metadata: 1

[12:32:06.566566] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:32:06.566622] sendSTAT:166 [0x37dd18]: STAT: STMc
[12:32:06.566738] process:540 [0x37dd18] audg
[12:32:06.566767] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:32:06.566792] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:32:06.566827] process:540 [0x37dd18] setd
[12:32:06.566847] sendSETDName:229 [10]: set playername: am335x-opt
[12:32:06.566897] process:540 [0x37dd18] setd
[12:32:06.566929] process:540 [0x37dd18] aude
[12:32:06.566948] process_aude:420 [0x37dd18] on/off using aude 1
[12:32:06.566968] sq_callback:273 [0x3d5140]: device set on/off 1
[12:32:06.566999] process:540 [0x37dd18] audg
[12:32:06.567018] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:32:06.567152] PlayerThread:409 [0x3d5140]: tick 4187935015
[12:32:06.567291] PlayerThread:558 [0x3d5140]: processing volume: 49 (-15.31)
[12:32:06.609921] process:540 [0x37dd18] strm
[12:32:06.610032] process_strm:244 [0x37dd18] strm command t
[12:32:06.610055] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:32:06.610074] sendSTAT:166 [0x37dd18]: STAT: STMt
[12:32:06.668769] stream_thread:337 [0x37dd18] headers: len: 568
HTTP/1.0 200 OK
Content-Type: audio/mpeg
Date: Sun, 08 Nov 2020 11:32:06 GMT
icy-description:Westdeutscher Rundfunk Koeln
icy-genre:wdr
icy-name:1Live, Westdeutscher Rundfunk Koeln
icy-pub:1
icy-url:http://www.1live.de
Server: dg-wdr-http_dus-ts_edge_87eb9ce8c801a059c787c52e6a4b4e6a
Cache-Control: no-cache, no-store
Access-Control-Allow-Origin: *
Access-Control-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type
Access-Control-Allow-Methods: GET, OPTIONS, HEAD
Connection: Close
Expires: Mon, 26 Jul 1997 05:00:00 GMT
icy-metaint:16000

[12:32:06.669155] sendRESP:199 [10]: RESP
[12:32:06.676038] process:540 [0x37dd18] cont
[12:32:06.676173] process_cont:380 cont metaint: 16000 loop: 0
[12:32:06.769233] stream_thread:436 [0x37dd18] streambuf read 16000 bytes
[12:32:06.769351] stream_thread:390 [0x37dd18] icy meta: len: 32
StreamTitle='1LIVE';
[12:32:06.769442] stream_thread:436 [0x37dd18] streambuf read 16000 bytes
[12:32:06.769511] stream_thread:436 [0x37dd18] streambuf read 10598 bytes
[12:32:06.769576] sendMETA:213 [10]: META

Retarting Squeeze2raop after crash: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-armv6hf-static -Z -I -b 10.0.0.2 -f /var/log/squeezeboxserver/raopbridge.log -d raop=debug -x /var/lib/squeezeboxserver/prefs/raopbridge.xml -i /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:32:08.283091] main:1535 Starting squeeze2raop version: v0.4.42.0 (Oct 30 2020 @ 17:44:40)

[12:32:08.283250] main:1538

!!!!!!!!!!!!!!!!!! ERROR LOADING CONFIG FILE !!!!!!!!!!!!!!!!!!!!!

[12:32:08.284090] Start:1282 Binding to 10.0.0.2
[12:32:08.284488] StartActiveRemote:1170 DACP port: 35509
[12:32:08.341700] AddRaopDevice:866 [0x3d5140]: adding renderer (am335x-opt)
[12:32:08.343775] raopcl_create:728 [0x74e08830]: using PCM coding
[12:32:29.285430] DelRaopDevice:960 [0x3d5140]: Raop device stopped

Retarting Squeeze2raop after crash: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-armv6hf-static -Z -I -b 10.0.0.2 -f /var/log/squeezeboxserver/raopbridge.log -d raop=debug -x /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:32:30.465768] main:1535 Starting squeeze2raop version: v0.4.42.0 (Oct 30 2020 @ 17:44:40)

[12:32:30.467727] Start:1282 Binding to 10.0.0.2
[12:32:30.468243] StartActiveRemote:1170 DACP port: 32907
[12:32:30.592430] AddRaopDevice:866 [0x3d5140]: adding renderer (am335x-opt)
[12:32:30.594249] raopcl_create:728 [0x76608840]: using PCM coding
[12:32:30.599122] output_raop_thread_init:126 [0x37dd18]: init output raop
[12:32:30.599199] output_init_common:293 allocating 1764000
[12:32:30.600197] slimproto:835 squeezelite [0x37dd18] <=> player [0x3d5140]
[12:32:30.600363] slimproto:836 [0x37dd18] connecting to 10.0.0.2:3483
[12:32:30.600567] slimproto:866 [0x37dd18] connected
[12:32:30.600596] sendHELO:118 [0x37dd18] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3
[12:32:30.617927] process_strm:241 [0x37dd18] strm command s
[12:32:30.618023] process_strm:316 [0x37dd18], strm s autostart: 3 transition period: 10 transition type: 0 codec: m
[12:32:30.618205] process_strm:348 [0x37dd18]: codec:m, ch:255, s:255, r:255
[12:32:30.694904] PlayerThread:558 [0x3d5140]: processing volume: 49 (-15.31)

Retarting Squeeze2raop after crash: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-armv6hf-static -Z -I -b 10.0.0.2 -f /var/log/squeezeboxserver/raopbridge.log -d raop=debug -x /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:32:31.645417] main:1535 Starting squeeze2raop version: v0.4.42.0 (Oct 30 2020 @ 17:44:40)

[12:32:31.646249] Start:1282 Binding to 10.0.0.2
[12:32:31.646468] StartActiveRemote:1170 DACP port: 48693
[12:32:31.727306] AddRaopDevice:866 [0x3d5140]: adding renderer (am335x-opt)
[12:32:31.729141] raopcl_create:728 [0x76608840]: using PCM coding
[12:32:31.733827] output_raop_thread_init:126 [0x37dd18]: init output raop
[12:32:31.733911] output_init_common:293 allocating 1764000
[12:32:31.735101] slimproto:835 squeezelite [0x37dd18] <=> player [0x3d5140]
[12:32:31.735588] slimproto:836 [0x37dd18] connecting to 10.0.0.2:3483
[12:32:31.735848] slimproto:866 [0x37dd18] connected
[12:32:31.735897] sendHELO:118 [0x37dd18] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3
[12:32:31.754744] process_strm:241 [0x37dd18] strm command s
[12:32:31.754845] process_strm:316 [0x37dd18], strm s autostart: 3 transition period: 10 transition type: 0 codec: m
[12:32:31.755031] process_strm:348 [0x37dd18]: codec:m, ch:255, s:255, r:255
[12:32:31.836429] PlayerThread:558 [0x3d5140]: processing volume: 49 (-15.31)

Retarting Squeeze2raop after crash: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-armv6hf-static -Z -I -b 10.0.0.2 -f /var/log/squeezeboxserver/raopbridge.log -d all=debug -x /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:32:36.343905] main:1535 Starting squeeze2raop version: v0.4.42.0 (Oct 30 2020 @ 17:44:40)

[12:32:36.344097] register_pcm:306 using pcm to decode aif,pcm
[12:32:36.344144] register_mad:429 using mad to decode mp3
[12:32:36.344164] register_alac:542 using alac to decode alc
[12:32:36.344180] register_flac:308 using flac to decode flc
[12:32:36.344197] register_faad:648 using faad to decode aac
[12:32:36.344213] register_vorbis:339 using vorbis to decode ogg
[12:32:36.344229] register_opus:282 using opus to decode ops
[12:32:36.344245] register_soxr:391 using soxr for resampling
[12:32:36.344911] Start:1282 Binding to 10.0.0.2
[12:32:36.345128] StartActiveRemote:1170 DACP port: 41063
[12:32:36.377596] AddRaopDevice:866 [0x3d5140]: adding renderer (am335x-opt)
[12:32:36.379423] raopcl_create:728 [0x76608840]: using PCM coding
[12:32:36.380017] stream_thread_init:464 [0x37dd18] streambuf size: 2097152
[12:32:36.384354] output_raop_thread_init:126 [0x37dd18]: init output raop
[12:32:36.384458] output_init_common:289 [0x1aeaa0]: outputbuf size: 0
[12:32:36.384479] output_init_common:293 allocating 1764000
[12:32:36.384562] decode_thread_init:158 [0x37dd18]: init decode
[12:32:36.384631] resample_init:342 [0x37dd18]: resampling sync recipe: 0x00, flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000, stopband_begin: 0.00000, phase_response: -1.0
[12:32:36.384750] mDNSsearchCallback:692 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:32:36.384775] discover_server:783 [0x37dd18] sending discovery
[12:32:36.386167] discover_server:815 [0x37dd18] got response from: 10.0.0.2:3483
[12:32:36.386269] slimproto:835 squeezelite [0x37dd18] <=> player [0x3d5140]
[12:32:36.386290] slimproto:836 [0x37dd18] connecting to 10.0.0.2:3483
[12:32:36.387480] slimproto:866 [0x37dd18] connected
[12:32:36.387581] sendHELO:117 [0x37dd18] mac: aa:aa:b0:e6:4f:a7
[12:32:36.387605] sendHELO:118 [0x37dd18] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3
[12:32:36.390012] process:540 [0x37dd18] strm
[12:32:36.390111] process_strm:244 [0x37dd18] strm command q
[12:32:36.390132] decode_flush:198 [0x37dd18]: decode flush
[12:32:36.390151] output_flush:334 [0x37dd18]: flush output buffer
[12:32:36.390174] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:32:36.400109] process:540 [0x37dd18] audg
[12:32:36.400202] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:32:36.400229] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:32:36.406432] process:540 [0x37dd18] strm
[12:32:36.406525] process_strm:241 [0x37dd18] strm command s
[12:32:36.406547] process_strm:316 [0x37dd18], strm s autostart: 3 transition period: 10 transition type: 0 codec: m
[12:32:36.406569] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:32:36.406587] sendSTAT:166 [0x37dd18]: STAT: STMf
[12:32:36.406641] process_strm:332 [0x37dd18]: set fade mode: 0
[12:32:36.406665] codec_open:227 codec open: 'm'
[12:32:36.406800] process_strm:348 [0x37dd18]: codec:m, ch:255, s:255, r:255
[12:32:36.406825] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:32:36.406890] connect_socket:154 [0x37dd18] connecting to 212.122.148.114:80
[12:32:36.483955] stream_sock:587 [0x37dd18] header: GET /wdr/1live/live/mp3/128/stream.mp3?ar-distributor=f0a1&_art=dj0yJmlwPTc5LjIwMy4xNTQuMTQzJmlkPWljc2N4bC14MmNzc3lsbGImdD0xNjA0OTE1NzYwJnM9Nzg2NmYyOWMjNmQ5NGJhOTU3MzAyYzlhY2QxZjc3ODA2ODYwOGI3MzE HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: /
Host: wdr-edge-300e-dus-ts-cdn.cast.addradio.de
User-Agent: iTunes/4.7.1 (Linux; N; Debian; armv7l-linux; DE; utf8) SqueezeCenter, Squeezebox Server, Logitech Media Server/7.9.4/1600144323
Icy-Metadata: 1

[12:32:36.484109] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:32:36.484138] sendSTAT:166 [0x37dd18]: STAT: STMc
[12:32:36.484299] process:540 [0x37dd18] audg
[12:32:36.484333] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:32:36.484356] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:32:36.484394] process:540 [0x37dd18] setd
[12:32:36.484415] sendSETDName:229 [10]: set playername: am335x-opt
[12:32:36.484533] process:540 [0x37dd18] setd
[12:32:36.484572] process:540 [0x37dd18] aude
[12:32:36.484591] process_aude:420 [0x37dd18] on/off using aude 1
[12:32:36.484613] sq_callback:273 [0x3d5140]: device set on/off 1
[12:32:36.484646] process:540 [0x37dd18] audg
[12:32:36.484665] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:32:36.484802] PlayerThread:409 [0x3d5140]: tick 4187964932
[12:32:36.484992] PlayerThread:558 [0x3d5140]: processing volume: 49 (-15.31)
[12:32:36.530852] process:540 [0x37dd18] strm
[12:32:36.530961] process_strm:244 [0x37dd18] strm command t
[12:32:36.530991] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:32:36.531019] sendSTAT:166 [0x37dd18]: STAT: STMt
[12:32:36.600100] stream_thread:337 [0x37dd18] headers: len: 568
HTTP/1.0 200 OK
Content-Type: audio/mpeg
Date: Sun, 08 Nov 2020 11:32:36 GMT
icy-description:Westdeutscher Rundfunk Koeln
icy-genre:wdr
icy-name:1Live, Westdeutscher Rundfunk Koeln
icy-pub:1
icy-url:http://www.1live.de
Server: dg-wdr-http_dus-ts_edge_87eb9ce8c801a059c787c52e6a4b4e6a
Cache-Control: no-cache, no-store
Access-Control-Allow-Origin: *
Access-Control-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type
Access-Control-Allow-Methods: GET, OPTIONS, HEAD
Connection: Close
Expires: Mon, 26 Jul 1997 05:00:00 GMT
icy-metaint:16000

[12:32:36.600349] sendRESP:199 [10]: RESP
[12:32:36.615639] process:540 [0x37dd18] cont
[12:32:36.615791] process_cont:380 cont metaint: 16000 loop: 0
[12:32:36.700538] stream_thread:436 [0x37dd18] streambuf read 16000 bytes
[12:32:36.700743] stream_thread:390 [0x37dd18] icy meta: len: 32
StreamTitle='1LIVE';
[12:32:36.700908] stream_thread:436 [0x37dd18] streambuf read 16000 bytes
[12:32:36.701021] sendMETA:213 [10]: META
[12:32:36.701097] stream_thread:436 [0x37dd18] streambuf read 10598 bytes
[12:32:36.701267] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:32:36.701311] sendSTAT:166 [0x37dd18]: STAT: STMt
[12:32:36.742319] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:32:36.742924] stream_thread:436 [0x37dd18] streambuf read 3962 bytes
[12:32:36.743136] stream_thread:436 [0x37dd18] streambuf read 357 bytes
[12:32:36.746303] stream_thread:436 [0x37dd18] streambuf read 2880 bytes
[12:32:36.748155] stream_thread:436 [0x37dd18] streambuf read 12763 bytes
[12:32:36.748380] stream_thread:436 [0x37dd18] streambuf read 196 bytes

Retarting Squeeze2raop after crash: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-armv6hf-static -Z -I -b 10.0.0.2 -f /var/log/squeezeboxserver/raopbridge.log -d raop=debug -x /var/lib/squeezeboxserver/prefs/raopbridge.xml -i /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:32:38.301029] main:1535 Starting squeeze2raop version: v0.4.42.0 (Oct 30 2020 @ 17:44:40)

[12:32:38.301866] Start:1282 Binding to 10.0.0.2
[12:32:38.302145] StartActiveRemote:1170 DACP port: 41797
[12:32:38.346706] AddRaopDevice:866 [0x3d5140]: adding renderer (am335x-opt)
[12:32:38.348709] raopcl_create:728 [0x76608840]: using PCM coding
[12:32:38.349245] mDNSsearchCallback:692 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:32:39.349339] PlayerThread:409 [0x3d5140]: tick 4187967797
[12:32:40.349713] PlayerThread:409 [0x3d5140]: tick 4187968797
[12:32:41.349957] PlayerThread:409 [0x3d5140]: tick 4187969797
[12:32:42.350214] PlayerThread:409 [0x3d5140]: tick 4187970798
[12:32:43.350401] PlayerThread:409 [0x3d5140]: tick 4187971798
[12:32:44.350725] PlayerThread:409 [0x3d5140]: tick 4187972798
[12:32:45.350987] PlayerThread:409 [0x3d5140]: tick 4187973798
[12:32:46.351249] PlayerThread:409 [0x3d5140]: tick 4187974799
[12:32:47.351507] PlayerThread:409 [0x3d5140]: tick 4187975799
[12:32:48.351721] PlayerThread:409 [0x3d5140]: tick 4187976799
[12:32:49.351961] PlayerThread:409 [0x3d5140]: tick 4187977799
[12:32:50.352127] PlayerThread:409 [0x3d5140]: tick 4187978800
[12:32:51.352415] PlayerThread:409 [0x3d5140]: tick 4187979800
[12:32:52.352714] PlayerThread:409 [0x3d5140]: tick 4187980800
[12:32:53.352916] PlayerThread:409 [0x3d5140]: tick 4187981800
[12:32:54.353112] PlayerThread:409 [0x3d5140]: tick 4187982801
[12:32:55.353312] PlayerThread:409 [0x3d5140]: tick 4187983801
[12:32:56.353556] PlayerThread:409 [0x3d5140]: tick 4187984801
[12:32:57.353757] PlayerThread:409 [0x3d5140]: tick 4187985801
[12:32:58.354034] PlayerThread:409 [0x3d5140]: tick 4187986802
[12:32:59.302793] Stop:1308 terminate search thread ...
[12:32:59.302933] Stop:1313 flush renderers ...
[12:32:59.302979] PlayerThread:409 [0x3d5140]: tick 4187987750
[12:32:59.303078] DelRaopDevice:960 [0x3d5140]: Raop device stopped
[12:32:59.303107] Stop:1317 terminate mDNS responder
[12:32:59.803837] Stop:1320 terminate main thread ...

Retarting Squeeze2raop after crash: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-armv6hf-static -Z -I -b 10.0.0.2 -f /var/log/squeezeboxserver/raopbridge.log -d raop=debug -x /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:33:00.454559] main:1535 Starting squeeze2raop version: v0.4.42.0 (Oct 30 2020 @ 17:44:40)

[12:33:00.455223] register_pcm:306 using pcm to decode aif,pcm
[12:33:00.455338] register_mad:429 using mad to decode mp3
[12:33:00.455360] register_alac:542 using alac to decode alc
[12:33:00.455377] register_flac:308 using flac to decode flc
[12:33:00.455394] register_faad:648 using faad to decode aac
[12:33:00.455410] register_vorbis:339 using vorbis to decode ogg
[12:33:00.455429] register_opus:282 using opus to decode ops
[12:33:00.455448] register_soxr:391 using soxr for resampling
[12:33:00.456386] Start:1282 Binding to 10.0.0.2
[12:33:00.456778] StartActiveRemote:1170 DACP port: 47453
[12:33:00.536726] AddRaopDevice:866 [0x3d5140]: adding renderer (am335x-opt)
[12:33:00.538792] raopcl_create:728 [0x76608840]: using PCM coding
[12:33:00.539360] stream_thread_init:464 [0x37dd18] streambuf size: 2097152
[12:33:00.543854] output_raop_thread_init:126 [0x37dd18]: init output raop
[12:33:00.544002] output_init_common:289 [0x1aeaa0]: outputbuf size: 0
[12:33:00.544034] output_init_common:293 allocating 1764000
[12:33:00.544163] decode_thread_init:158 [0x37dd18]: init decode
[12:33:00.544260] resample_init:342 [0x37dd18]: resampling sync recipe: 0x00, flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000, stopband_begin: 0.00000, phase_response: -1.0
[12:33:00.544448] mDNSsearchCallback:692 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:33:00.544500] discover_server:783 [0x37dd18] sending discovery
[12:33:00.545487] discover_server:815 [0x37dd18] got response from: 10.0.0.2:3483
[12:33:00.545586] slimproto:835 squeezelite [0x37dd18] <=> player [0x3d5140]
[12:33:00.545609] slimproto:836 [0x37dd18] connecting to 10.0.0.2:3483
[12:33:00.545847] slimproto:866 [0x37dd18] connected
[12:33:00.545879] sendHELO:117 [0x37dd18] mac: aa:aa:b0:e6:4f:a7
[12:33:00.545900] sendHELO:118 [0x37dd18] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3
[12:33:00.549330] process:540 [0x37dd18] strm
[12:33:00.549441] process_strm:244 [0x37dd18] strm command q
[12:33:00.549474] decode_flush:198 [0x37dd18]: decode flush
[12:33:00.549504] output_flush:334 [0x37dd18]: flush output buffer
[12:33:00.549535] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:33:00.562880] process:540 [0x37dd18] audg
[12:33:00.562979] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:33:00.563007] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:33:00.570572] process:540 [0x37dd18] strm
[12:33:00.570673] process_strm:241 [0x37dd18] strm command s
[12:33:00.570695] process_strm:316 [0x37dd18], strm s autostart: 3 transition period: 10 transition type: 0 codec: m
[12:33:00.570718] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:33:00.570736] sendSTAT:166 [0x37dd18]: STAT: STMf
[12:33:00.570791] process_strm:332 [0x37dd18]: set fade mode: 0
[12:33:00.570815] codec_open:227 codec open: 'm'
[12:33:00.570944] process_strm:348 [0x37dd18]: codec:m, ch:255, s:255, r:255
[12:33:00.570970] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:33:00.571036] connect_socket:154 [0x37dd18] connecting to 212.122.148.114:80
[12:33:00.650697] stream_sock:587 [0x37dd18] header: GET /wdr/1live/live/mp3/128/stream.mp3?ar-distributor=f0a1&_art=dj0yJmlwPTc5LjIwMy4xNTQuMTQzJmlkPWljc2N4bC14MmNzc3lsbGImdD0xNjA0OTE1NzYwJnM9Nzg2NmYyOWMjNmQ5NGJhOTU3MzAyYzlhY2QxZjc3ODA2ODYwOGI3MzE HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: /
Host: wdr-edge-300e-dus-ts-cdn.cast.addradio.de
User-Agent: iTunes/4.7.1 (Linux; N; Debian; armv7l-linux; DE; utf8) SqueezeCenter, Squeezebox Server, Logitech Media Server/7.9.4/1600144323
Icy-Metadata: 1

[12:33:00.650849] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:33:00.650885] sendSTAT:166 [0x37dd18]: STAT: STMc
[12:33:00.651063] process:540 [0x37dd18] audg
[12:33:00.651095] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:33:00.651121] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:33:00.651162] process:540 [0x37dd18] setd
[12:33:00.651183] sendSETDName:229 [10]: set playername: am335x-opt
[12:33:00.651245] process:540 [0x37dd18] setd
[12:33:00.651278] process:540 [0x37dd18] aude
[12:33:00.651297] process_aude:420 [0x37dd18] on/off using aude 1
[12:33:00.651316] sq_callback:273 [0x3d5140]: device set on/off 1
[12:33:00.651348] process:540 [0x37dd18] audg
[12:33:00.651367] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:33:00.651457] PlayerThread:409 [0x3d5140]: tick 4187989099
[12:33:00.651635] PlayerThread:558 [0x3d5140]: processing volume: 49 (-15.31)
[12:33:00.692253] process:540 [0x37dd18] strm
[12:33:00.692395] process_strm:244 [0x37dd18] strm command t
[12:33:00.692424] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:33:00.692443] sendSTAT:166 [0x37dd18]: STAT: STMt
[12:33:00.817323] stream_thread:337 [0x37dd18] headers: len: 568
HTTP/1.0 200 OK
Content-Type: audio/mpeg
Date: Sun, 08 Nov 2020 11:33:00 GMT
icy-description:Westdeutscher Rundfunk Koeln
icy-genre:wdr
icy-name:1Live, Westdeutscher Rundfunk Koeln
icy-pub:1
icy-url:http://www.1live.de
Server: dg-wdr-http_dus-ts_edge_87eb9ce8c801a059c787c52e6a4b4e6a
Cache-Control: no-cache, no-store
Access-Control-Allow-Origin: *
Access-Control-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type
Access-Control-Allow-Methods: GET, OPTIONS, HEAD
Connection: Close
Expires: Mon, 26 Jul 1997 05:00:00 GMT
icy-metaint:16000

[12:33:00.817590] sendRESP:199 [10]: RESP
[12:33:00.824148] process:540 [0x37dd18] cont
[12:33:00.824238] process_cont:380 cont metaint: 16000 loop: 0
[12:33:00.917693] stream_thread:436 [0x37dd18] streambuf read 16000 bytes
[12:33:00.917812] stream_thread:390 [0x37dd18] icy meta: len: 32
StreamTitle='1LIVE';
[12:33:00.917899] stream_thread:436 [0x37dd18] streambuf read 16000 bytes
[12:33:00.917973] sendMETA:213 [10]: META
[12:33:00.918015] stream_thread:436 [0x37dd18] streambuf read 10598 bytes
[12:33:00.918099] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:33:00.918120] sendSTAT:166 [0x37dd18]: STAT: STMt
[12:33:00.941481] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.941607] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.941646] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.941776] stream_thread:436 [0x37dd18] streambuf read 1082 bytes
[12:33:00.941815] stream_thread:436 [0x37dd18] streambuf read 357 bytes
[12:33:00.941889] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.942009] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.942132] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.942250] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.942381] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.942497] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.942623] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.942737] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.942869] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.942983] stream_thread:436 [0x37dd18] streambuf read 1440 bytes
[12:33:00.943146] stream_thread:436 [0x37dd18] streambuf read 1243 bytes
[12:33:00.943180] stream_thread:436 [0x37dd18] streambuf read 196 bytes

Retarting Squeeze2raop after crash: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/RaopBridge/Bin/squeeze2raop-armv6hf-static -Z -I -b 10.0.0.2 -f /var/log/squeezeboxserver/raopbridge.log -d raop=debug -x /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:33:01.667247] main:1535 Starting squeeze2raop version: v0.4.42.0 (Oct 30 2020 @ 17:44:40)

[12:33:01.667479] register_pcm:306 using pcm to decode aif,pcm
[12:33:01.667640] register_mad:429 using mad to decode mp3
[12:33:01.667660] register_alac:542 using alac to decode alc
[12:33:01.667677] register_flac:308 using flac to decode flc
[12:33:01.667693] register_faad:648 using faad to decode aac
[12:33:01.667710] register_vorbis:339 using vorbis to decode ogg
[12:33:01.667727] register_opus:282 using opus to decode ops
[12:33:01.667743] register_soxr:391 using soxr for resampling
[12:33:01.668434] Start:1282 Binding to 10.0.0.2
[12:33:01.668680] StartActiveRemote:1170 DACP port: 56963
[12:33:01.703344] AddRaopDevice:866 [0x3d5140]: adding renderer (am335x-opt)
[12:33:01.705295] raopcl_create:728 [0x76608840]: using PCM coding
[12:33:01.705926] stream_thread_init:464 [0x37dd18] streambuf size: 2097152
[12:33:01.711075] output_raop_thread_init:126 [0x37dd18]: init output raop
[12:33:01.711169] output_init_common:289 [0x1aeaa0]: outputbuf size: 0
[12:33:01.711190] output_init_common:293 allocating 1764000
[12:33:01.711288] decode_thread_init:158 [0x37dd18]: init decode
[12:33:01.711363] resample_init:342 [0x37dd18]: resampling sync recipe: 0x00, flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000, stopband_begin: 0.00000, phase_response: -1.0
[12:33:01.711514] mDNSsearchCallback:692 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml
[12:33:01.711548] discover_server:783 [0x37dd18] sending discovery
[12:33:01.713429] discover_server:815 [0x37dd18] got response from: 10.0.0.2:3483
[12:33:01.713599] slimproto:835 squeezelite [0x37dd18] <=> player [0x3d5140]
[12:33:01.713644] slimproto:836 [0x37dd18] connecting to 10.0.0.2:3483
[12:33:01.715900] slimproto:866 [0x37dd18] connected
[12:33:01.716026] sendHELO:117 [0x37dd18] mac: aa:aa:b0:e6:4f:a7
[12:33:01.716051] sendHELO:118 [0x37dd18] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3
[12:33:01.722233] process:540 [0x37dd18] strm
[12:33:01.722368] process_strm:244 [0x37dd18] strm command q
[12:33:01.722403] decode_flush:198 [0x37dd18]: decode flush
[12:33:01.722426] output_flush:334 [0x37dd18]: flush output buffer
[12:33:01.722454] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:33:01.736050] process:540 [0x37dd18] audg
[12:33:01.736151] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:33:01.736178] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:33:01.749921] process:540 [0x37dd18] strm
[12:33:01.750062] process_strm:241 [0x37dd18] strm command s
[12:33:01.750102] process_strm:316 [0x37dd18], strm s autostart: 3 transition period: 10 transition type: 0 codec: m
[12:33:01.750130] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:33:01.750148] sendSTAT:166 [0x37dd18]: STAT: STMf
[12:33:01.750198] process_strm:332 [0x37dd18]: set fade mode: 0
[12:33:01.750224] codec_open:227 codec open: 'm'
[12:33:01.750396] process_strm:348 [0x37dd18]: codec:m, ch:255, s:255, r:255
[12:33:01.750438] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:33:01.750508] connect_socket:154 [0x37dd18] connecting to 212.122.148.114:80
[12:33:01.824673] stream_sock:587 [0x37dd18] header: GET /wdr/1live/live/mp3/128/stream.mp3?ar-distributor=f0a1&_art=dj0yJmlwPTc5LjIwMy4xNTQuMTQzJmlkPWljc2N4bC14MmNzc3lsbGImdD0xNjA0OTE1NzYwJnM9Nzg2NmYyOWMjNmQ5NGJhOTU3MzAyYzlhY2QxZjc3ODA2ODYwOGI3MzE HTTP/1.0
Cache-Control: no-cache
Connection: close
Accept: /
Host: wdr-edge-300e-dus-ts-cdn.cast.addradio.de
User-Agent: iTunes/4.7.1 (Linux; N; Debian; armv7l-linux; DE; utf8) SqueezeCenter, Squeezebox Server, Logitech Media Server/7.9.4/1600144323
Icy-Metadata: 1

[12:33:01.824849] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:33:01.824881] sendSTAT:166 [0x37dd18]: STAT: STMc
[12:33:01.825053] process:540 [0x37dd18] audg
[12:33:01.825095] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:33:01.825120] sq_callback:277 [0x3d5140]: device off or not controlled by LMS
[12:33:01.825155] process:540 [0x37dd18] setd
[12:33:01.825176] sendSETDName:229 [10]: set playername: am335x-opt
[12:33:01.825238] process:540 [0x37dd18] setd
[12:33:01.825268] process:540 [0x37dd18] aude
[12:33:01.825287] process_aude:420 [0x37dd18] on/off using aude 1
[12:33:01.825306] sq_callback:273 [0x3d5140]: device set on/off 1
[12:33:01.825339] process:540 [0x37dd18] audg
[12:33:01.825358] process_audg:437 [0x37dd18] (old) audg gainL: 46 gainR: 46
[12:33:01.825442] PlayerThread:409 [0x3d5140]: tick 4187990273
[12:33:01.825629] PlayerThread:558 [0x3d5140]: processing volume: 49 (-15.31)
[12:33:01.867987] process:540 [0x37dd18] strm
[12:33:01.868112] process_strm:244 [0x37dd18] strm command t
[12:33:01.868137] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:33:01.868156] sendSTAT:166 [0x37dd18]: STAT: STMt
[12:33:02.008152] stream_thread:337 [0x37dd18] headers: len: 568
HTTP/1.0 200 OK
Content-Type: audio/mpeg
Date: Sun, 08 Nov 2020 11:33:01 GMT
icy-description:Westdeutscher Rundfunk Koeln
icy-genre:wdr
icy-name:1Live, Westdeutscher Rundfunk Koeln
icy-pub:1
icy-url:http://www.1live.de
Server: dg-wdr-http_dus-ts_edge_87eb9ce8c801a059c787c52e6a4b4e6a
Cache-Control: no-cache, no-store
Access-Control-Allow-Origin: *
Access-Control-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type
Access-Control-Allow-Methods: GET, OPTIONS, HEAD
Connection: Close
Expires: Mon, 26 Jul 1997 05:00:00 GMT
icy-metaint:16000

[12:33:02.008406] sendRESP:199 [10]: RESP
[12:33:02.018457] process:540 [0x37dd18] cont
[12:33:02.018587] process_cont:380 cont metaint: 16000 loop: 0
[12:33:02.108520] stream_thread:436 [0x37dd18] streambuf read 16000 bytes
[12:33:02.108656] stream_thread:390 [0x37dd18] icy meta: len: 32
StreamTitle='1LIVE';
[12:33:02.108751] stream_thread:436 [0x37dd18] streambuf read 16000 bytes
[12:33:02.108831] sendMETA:213 [10]: META
[12:33:02.108888] stream_thread:436 [0x37dd18] streambuf read 10598 bytes
[12:33:02.108988] sendSTAT:142 [0x37dd18]: ms_played: 0
[12:33:02.109021] sendSTAT:166 [0x37dd18]: STAT: STMt`

x-sven commented

Does anyone know a way to do a downgrade? Version "before" works like a charm :-)

philippe44 commented

Have you removed and re-installed the whole plugin? I think I had a build issue that led to incorrect binary. I've re-uploaded it, but you need to de-install and re-install. Other than that, it's surprising that the changes made in latest version are the cause of an issue as it only deals with the order of trying HTTPS vs HTTP. Can you tell me exactly, from LMS, what stream are you listening to? Does it happens when listening to local files as well?

Can you also force proxied streaming in LMS?

  • 🎉1
x-sven commented

YES, it works now. I've reinstalled the plugin. Seems to work now, great. Thank you!
(If it still matters, I was using: http://www.fritz.de/live.m3u)

cribskip commented

Thanks @philippe44! I've done the same as @x-sven and reinstalled and now it's back to a working state, great!

I've been trying to listen to yt music with your plugin as well as local music and m3u streams. All did not work.