Announce: UPnPBridge = integrate UPnP/DLNA players with LMS (squeeze2upnp)

[stereoptic]

My guess is that this is a networking problem that is because of the OpenVPN connection.

UPnP-AV devices and players discover each other via UDP broadcast messages.
I expect that when the plugin is restarted it sends out a broadcast message to announce its presence.
However, I would also expect it to send one out periodically. Perhaps that period is too long to be useful in your set-up.

In theory the player should also send out a message to announce its presence ... but perhaps OpenVPN is not relaying that though to the internal network.

Telling the player the IP address of the UPnP server gets around the issue.

I have a feeling that the USBAudioPlayerPro is trying to connect to mysqueezebox.com? I am trying to determine why, how, and if it is possible to edit that on the app. Other than that, is there a setting in LMS or piCoreplayer to modify this?

[Smitty2k1]

I got this plugin successfully set up on my server running as a docker. I'm trying to play on my Wiim Mini streamer, which supports DLNA. However I'm getting skips/pauses at the end of each track. I have gapless enabled and have tried different streaming and transcoding methods but I keep getting these weird skips at the end of a track. Here's the log:


Starting Squeeze2upnp: /config/cache/InstalledPlugins/Plugins/UPnPBridge/Bin/squeeze2upnp-x86-64-static -Z -I -f /config/logs/upnpbridge.log -x /config/prefs/upnpbridge.xml
[13:16:22.577342] main:1774 Starting squeeze2upnp version: v1.82.2 (Feb 21 2022 @ 19:02:23)
[13:16:22.630549] Start:1536 Binding to 192.168.1.17:49153 (http:0)
[13:16:22.677035] AddMRDevice:1423 [0xc44b00]: adding renderer (WiiM Mini-E1D4)
[13:16:22.688766] slimproto:861 squeezelite [0xbbb140] <=> player [0xc44b00]
[13:16:22.688935] slimproto:862 [0xbbb140] connecting to 192.168.1.17:3483
[13:16:22.689174] slimproto:892 [0xbbb140] connected
[13:16:22.689349] sendHELO:135 [0xbbb140] cap: CanHTTPS=1,Model=squeezelite,ModelName=UPnPBridge, AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate =48000,aac,ogg,flc,alc,wav,aif,pcm,mp3
[13:16:22.694653] MasterHandler:1054 [0xc44b00]: subscribe success
[13:16:22.704647] process_strm:238 [0xbbb140] strm command s
[13:16:22.704833] process_strm:307 [0xbbb140], strm s autostart: 1 transition period: 10 transition type: 0 codec: f
[13:16:22.704960] sendSTAT:169 [0xbbb140]: STAT:[STMf] msplayed 0
[13:16:22.710836] output_start:81 [0xbbb140]: start thread 0
[13:16:22.711059] process_start:1220 [0xbbb140]: codec:c, ch:0, s:0, r:0
[13:16:22.711158] process_start:1226 [0xbbb140]: something went wrong starting process 1
[13:16:22.711354] output_http_thread:389 [0xbbb140]: end thread 0 (0 bytes)
[13:16:22.711686] sendSTAT:169 [0xbbb140]: STAT:[STMc] msplayed 0
[13:16:22.711805] process_strm:342 [0xbbb140] no matching codec c
[13:16:22.711936] sendSTAT:169 [0xbbb140]: STAT:[STMn] msplayed 0
[13:16:22.713763] sendSTAT:169 [0xbbb140]: STAT:[STMf] msplayed 0
[13:16:22.713950] AVTStop:217 [0xc44b00]: uPNP stop (cookie (nil))
[13:16:22.732638] process_strm:238 [0xbbb140] strm command s
[13:16:22.733016] process_strm:307 [0xbbb140], strm s autostart: 1 transition period: 10 transition type: 0 codec: f
[13:16:22.733172] sendSTAT:169 [0xbbb140]: STAT:[STMf] msplayed 0
[13:16:22.737710] output_start:81 [0xbbb140]: start thread 0
[13:16:22.738099] sq_callback:343 [0xc44b00]:
artist:Pink Floyd
album:The Dark Side Of The Moon
title:Money
genre:Progressive Rock
duration:383.200
size:0
cover:http://192.168.1.17:9000/music/5e9015cf/cover_.jpg
offset:0
[13:16:22.738308] sq_callback:382 [0xc44b00]: set current URI (s:0) http://192.168.1.17:42853/bridge-2.flac
[13:16:22.738529] AVTSetURI:91 [0xc44b00]: uPNP setURI http://192.168.1.17:42853/bridge-2.flac (cookie 0x1)
[13:16:22.739055] process_start:1220 [0xbbb140]: codec:c, ch:0, s:0, r:0
[13:16:22.739426] sendSTAT:169 [0xbbb140]: STAT:[STMc] msplayed 0
[13:16:22.841015] AVTSetPlayMode:167 [0xc44b00]: uPNP set play mode (cookie 0x2)
[13:16:22.841484] AVTPlay:151 [0xc44b00]: uPNP play (cookie 0x3)
[13:16:22.997632] output_http_thread:157 [0xbbb140]: got HTTP connection 14
[13:16:22.997893] output_http_thread:186 [0xbbb140]: drain is 2621440 (waited 259)
[13:16:22.999021] handle_http:483 [0xbbb140]: received GET /bridge-2.flac HTTP/1.1
[13:16:22.999249] handle_http:486 [0xbbb140]: HTTP headers
Host: 192.168.1.17:42853
User-Agent: Lavf/58.45.100
Icy-MetaData: 1
Connection: close

[13:16:22.999412] handle_http:573 [0xbbb140]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac
Transfer-Encoding: chunked


[13:16:23.063065] AddMRDevice:1423 [0xc46e28]: adding renderer (Marantz NR1509)
[13:16:23.078422] XMLGetFirstDocumentItem:180 ixmlNode_getFirstChild(tmpNode) returned (nil) (nil)
[13:16:23.078624] AddMRDevice:1444 [0xc46e28] unable to get protocol info, set <forced_mimetypes>
[13:16:23.080262] slimproto:861 squeezelite [0xbbf608] <=> player [0xc46e28]
[13:16:23.080441] slimproto:862 [0xbbf608] connecting to 192.168.1.17:3483
[13:16:23.080730] slimproto:892 [0xbbf608] connected
[13:16:23.080875] sendHELO:135 [0xbbf608] cap: CanHTTPS=1,Model=squeezelite,ModelName=UPnPBridge, AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate =48000,
[13:16:23.094852] MasterHandler:1054 [0xc46e28]: subscribe success
[13:16:23.356617] _SyncNotifState:687 WiiM Mini-E1D4: uPNP playing
[13:16:23.851853] sendSTAT:169 [0xbbb140]: STAT:[STMs] msplayed 0
[13:21:38.236476] output_http_thread:306 [0xbbb140]: draining (43286528 bytes)
[13:21:38.236907] sendSTAT:169 [0xbbb140]: STAT:[STMd] msplayed 313000
[13:21:38.254714] process_strm:238 [0xbbb140] strm command s
[13:21:38.254952] process_strm:307 [0xbbb140], strm s autostart: 1 transition period: 10 transition type: 0 codec: f
[13:21:38.255204] sendSTAT:169 [0xbbb140]: STAT:[STMf] msplayed 313000
[13:21:38.258770] output_start:81 [0xbbb140]: start thread 1
[13:21:38.259087] sq_callback:343 [0xc44b00]:
artist:Pink Floyd
album:The Dark Side Of The Moon
title:Us And Them
genre:Progressive Rock
duration:469.226
size:0
cover:http://192.168.1.17:9000/music/5e9015cf/cover_.jpg
offset:1
[13:21:38.259282] sq_callback:369 [0xc44b00]: next URI gapped (s:0) http://192.168.1.17:58261/bridge-3.flac
[13:21:38.259421] process_start:1220 [0xbbb140]: codec:c, ch:0, s:0, r:0
[13:21:38.259696] sendSTAT:169 [0xbbb140]: STAT:[STMc] msplayed 313000
[13:21:42.560494] output_http_thread:258 [0xbbb140]: self-exit
[13:21:42.561546] output_http_thread:389 [0xbbb140]: end thread 0 (43792406 bytes)
[13:22:47.697566] AVTSetURI:91 [0xc44b00]: uPNP setURI http://192.168.1.17:58261/bridge-3.flac (cookie 0x48c)
[13:22:47.698355] AVTPlay:151 [0xc44b00]: uPNP play (cookie 0x48d)
[13:22:47.698649] _SyncNotifState:664 [0xc44b00]: gapped transition (null)
[13:22:47.869176] output_http_thread:157 [0xbbb140]: got HTTP connection 11
[13:22:47.869633] output_http_thread:186 [0xbbb140]: drain is 2621440 (waited 69610)
[13:22:47.871210] handle_http:483 [0xbbb140]: received GET /bridge-3.flac HTTP/1.1
[13:22:47.871513] handle_http:486 [0xbbb140]: HTTP headers
Host: 192.168.1.17:58261
User-Agent: Lavf/58.45.100
Icy-MetaData: 1
Connection: close

[13:22:47.871807] handle_http:573 [0xbbb140]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac
Transfer-Encoding: chunked


[13:22:48.201644] _SyncNotifState:687 WiiM Mini-E1D4: uPNP playing
[13:22:48.202532] sendSTAT:169 [0xbbb140]: STAT:[STMs] msplayed 0

[linn_tyke]

After one track in a queue the playback stops. Can anyone help:

[12:20:48.953] main:1745 Starting squeeze2upnp version: v1.82.2 (Feb 21 2022 @ 19:04:48)
[12:20:48.956] output_init:647 success loading FLAC encoder
[12:20:48.956] Start:1507 Binding to 192.168.1.3:49152 (http:0)
[12:20:51.431] AddMRDevice:1399 [004C42D8]: adding renderer (Linn Music Room:UPnP AV)
[12:20:51.445] MasterHandler:1036 [004C42D8]: subscribe success
[12:20:51.445] _ProcessVolume:747 [004C42D8]: UPnP Volume local change 24:-1 (master)
[12:20:51.526] slimproto:861 squeezelite [00510FF8] <=> player [004C42D8]
[12:20:51.526] slimproto:862 [00510FF8] connecting to 192.168.1.3:3483
[12:20:51.526] slimproto:892 [00510FF8] connected
[12:20:51.526] sendHELO:135 [00510FF8] cap: CanHTTPS=1,Model=squeezelite,ModelName=UPnPBridge, AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate =192000,flc,pcm
[12:20:51.542] AVTStop:216 [004C42D8]: uPNP stop (cookie 00000000)
[12:20:58.770] AVTStop:216 [004C42D8]: uPNP stop (cookie 00000001)
[12:20:58.789] process_strm:238 [00510FF8] strm command s
[12:20:58.789] process_strm:307 [00510FF8], strm s autostart: 1 transition period: 0 transition type: 0 codec: f
[12:20:58.789] sendSTAT:169 [00510FF8]: STAT:[STMf] msplayed 0
[12:20:58.789] output_start:81 [00510FF8]: start thread 0
[12:20:58.789] sq_callback:340 [004C42D8]:
artist:
album:
title:Streaming from LMS
genre:
duration:0.000
size:0
cover:
offset:0
[12:20:58.789] sq_callback:379 [004C42D8]: set current URI (s:0) http://192.168.1.3:50170/bridge-1.flac
[12:20:58.789] AVTSetURI:90 [004C42D8]: uPNP setURI http://192.168.1.3:50170/bridge-1.flac (cookie 00000002)
[12:20:58.789] process_start:1208 [00510FF8]: codec:c, ch:0, s:0, r:0
[12:20:58.789] sendSTAT:169 [00510FF8]: STAT:[STMc] msplayed 0
[12:20:58.801] output_http_thread:157 [00510FF8]: got HTTP connection 1220
[12:20:58.825] AVTSetPlayMode:166 [004C42D8]: uPNP set play mode (cookie 00000003)
[12:20:58.825] AVTPlay:150 [004C42D8]: uPNP play (cookie 00000003)
[12:20:58.825] CtrlSetVolume:244 [004C42D8]: uPNP volume 24 (cookie 00000003)
[12:20:58.903] output_http_thread:186 [00510FF8]: drain is 2621440 (waited 109)
[12:20:58.903] handle_http:483 [00510FF8]: received GET /bridge-1.flac HTTP/1.1
[12:20:58.903] handle_http:486 [00510FF8]: HTTP headers
Host: 192.168.1.3:50170
User-Agent: LWIP/1.4.2-linn
Connection: close
Icy-MetaData: 1
Range: bytes=0-

[12:20:58.903] handle_http:573 [00510FF8]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/x-flac
Transfer-Encoding: chunked


[12:20:58.909] read_line:589 fd: 1220 read error: 10054 Unknown error

[12:20:58.909] http_parse:513 cannot read method
[12:20:58.909] handle_http:478 [00510FF8]: http parsing error (null)
[12:20:58.909] output_http_thread:209 [00510FF8]: HTTP close 1220 (bytes 131072) (n:2 res:0)
[12:20:58.909] output_http_thread:157 [00510FF8]: got HTTP connection 1220
[12:20:59.117] handle_http:483 [00510FF8]: received GET /bridge-1.flac HTTP/1.1
[12:20:59.117] handle_http:486 [00510FF8]: HTTP headers
Host: 192.168.1.3:50170
User-Agent: LWIP/1.4.2-linn
Connection: close
Icy-MetaData: 1
Range: bytes=0-

[12:20:59.117] handle_http:573 [00510FF8]: responding:
HTTP/1.1 206 Partial Content
Server: squeezebox-bridge
Connection: close
Content-Type: audio/x-flac
Content-Range: bytes 0-131072/*
Transfer-Encoding: chunked


[12:21:00.335] _SyncNotifState:684 Linn Music Room:UPnP AV: uPNP playing
[12:21:01.333] sendSTAT:169 [00510FF8]: STAT:[STMs] msplayed 0
[12:21:32.374] output_http_thread:306 [00510FF8]: draining (11108352 bytes)
[12:21:32.374] sendSTAT:169 [00510FF8]: STAT:[STMd] msplayed 31000
[12:21:36.720] output_http_thread:258 [00510FF8]: self-exit
[12:21:36.720] output_http_thread:389 [00510FF8]: end thread 0 (11582736 bytes)
[12:22:53.496] _SyncNotifState:634 [004C42D8]: uPNP transition

[Riccardo]

What Operating system does that run? A little googling says it runs linux, which should be fine.

Hello.
I need help about LMS /UPnp Bridge/Hqplayerd (lms loses player)
My sistem is
Linux Mint 20 Cinnamon 4.06.7 Intel i5 3550
Logitech Media Server Versione: 8.2.0 - 1627922070
UpnP Bridge 1.82.2
Hqplayerd 4.32.0-133
I would post the entire log but it is too long so is impossible, Here the last
[11:04:42.051558] handle_http:573 [0x6d3680]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac


[11:04:42.482524] read_line:589 fd: 24 read error: 104 Connection reset by peer
[11:04:42.482569] http_parse:513 cannot read method
[11:04:42.482587] handle_http:478 [0x6d3680]: http parsing error (null)
[11:04:42.482593] output_http_thread:209 [0x6d3680]: HTTP close 24 (bytes 5570560) (n:2 res:0)
[11:04:42.482595] ActionHandler:935 Error in action callback -- -208 (cookie 0x4)
[11:04:42.812537] ActionHandler:935 Error in action callback -- -204 (cookie 0x5)
[11:04:42.812607] ActionHandler:935 Error in action callback -- -204 (cookie 0x6)
[11:04:43.312784] ActionHandler:935 Error in action callback -- -204 (cookie 0x7)
[11:04:43.812943] ActionHandler:935 Error in action callback -- -204 (cookie 0x8)
[11:04:43.813026] ActionHandler:935 Error in action callback -- -204 (cookie 0x9)
[11:04:47.136395] UpdateThread:1135 [0x75d200]: renderer bye-bye: HQPlayerEmbedded:SignalystHQPlayer4
[11:04:47.239464] output_http_thread:389 [0x6d3680]: end thread 0 (5570560 bytes)
[11:04:47.239621] output_flush:611 [0x6d3680]: flush output buffer
[11:04:47.239663] output_close:642 [0x6d3680] close media renderer

Thanks for attentions

[Paul Webster]

[11:04:42.482524] read_line:589 fd: 24 read error: 104 Connection reset by peer

It looks like the player went away/crashed/reset ...

Any logs on the player?
Has it worked before with LMS?
Has it worked with FLAC from LMS before?

[Riccardo]

It looks like the player went away/crashed/reset ...

Any logs on the player?
Has it worked before with LMS?
Has it worked with FLAC from LMS before?

Now i hav'nt the error log, later probably
The proble was OLD and known but the problem of instability of the relationship between lms and hqplayerd VIA the UPnP Bridge plugin is there but never like now.
it was quite small but not absent with hqplayerd 4.27.2.97. A couple of years ago Philippe had modified the plugin to adapt it to Hqplayerd but some new problem must have arisen
below the log of when it works

[12:20:13.914610] handle_http:573 [0x6d3680]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac


[12:20:13.914620] output_http_thread:209 [0x6d3680]: HTTP close 14 (bytes 0) (n:1 res:0)
[12:20:13.914814] output_http_thread:157 [0x6d3680]: got HTTP connection 14
[12:20:13.915012] handle_http:483 [0x6d3680]: received GET /bridge-12.flac HTTP/1.1
[12:20:13.915024] handle_http:486 [0x6d3680]: HTTP headers
Host: 192.168.1.153
Connection: close
User-Agent: SignalystHQPlayer/4
Accept: */*

[12:20:13.915051] handle_http:573 [0x6d3680]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac


[12:20:13.940539] read_line:589 fd: 14 read error: 104 Connection reset by peer
[12:20:13.940579] http_parse:513 cannot read method
[12:20:13.940587] handle_http:478 [0x6d3680]: http parsing error (null)
[12:20:13.940593] output_http_thread:209 [0x6d3680]: HTTP close 14 (bytes 2457599) (n:1 res:0)
[12:20:14.060905] output_http_thread:157 [0x6d3680]: got HTTP connection 14
[12:20:14.061150] handle_http:483 [0x6d3680]: received HEAD /bridge-12.flac HTTP/1.1
[12:20:14.061175] handle_http:486 [0x6d3680]: HTTP headers
Host: 192.168.1.153
Connection: close
User-Agent: SignalystHQPlayer/4
Accept: */*

[12:20:14.061211] handle_http:573 [0x6d3680]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac


[12:20:14.061223] output_http_thread:209 [0x6d3680]: HTTP close 14 (bytes 2457599) (n:1 res:0)
[12:20:14.061397] output_http_thread:157 [0x6d3680]: got HTTP connection 14
[12:20:14.061632] handle_http:483 [0x6d3680]: received GET /bridge-12.flac HTTP/1.1
[12:20:14.061645] handle_http:486 [0x6d3680]: HTTP headers
Host: 192.168.1.153
Connection: close
User-Agent: SignalystHQPlayer/4
Accept: */*

[12:20:14.061655] handle_http:554 [0x6d3680]: re-opening a connection at 2457599
[12:20:14.061663] handle_http:556 [0x6d3680]: head is lost 2457599
[12:20:14.061685] handle_http:573 [0x6d3680]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac


[12:20:14.295819] _SyncNotifState:687 HQPlayerEmbedded:SignalystHQPlayer4: uPNP playing
[12:20:14.295850] sq_notify:603 [0x6d3680] play ignored
[12:20:14.296208] sq_notify:715 [0x6d3680] track 12 started by info at 2047497

[Riccardo]

It looks like the player went away/crashed/reset ...

Any logs on the player?
Has it worked before with LMS?
Has it worked with FLAC from LMS before?

I replied minutes ago but post is in approval?
By the way here a link of old problem.
https://forums.slimdevices.com/showt...starting/page3

Unixman posts but pc is mine

[Riccardo]

It looks like the player went away/crashed/reset ...

Any logs on the player?
Has it worked before with LMS?
Has it worked with FLAC from LMS before?

---------------------------------------------
At the moment I have no log of the reader cmq for some time I use LMS with UPnP Bridge and Hqplayerd, as highlighted in the previous post
this a log when it play well
[12:57:04.384901] handle_http:573 [0x6d3680]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac


[12:57:04.384912] output_http_thread:209 [0x6d3680]: HTTP close 14 (bytes 0) (n:1 res:0)
[12:57:04.385087] output_http_thread:157 [0x6d3680]: got HTTP connection 14
[12:57:04.385348] handle_http:483 [0x6d3680]: received GET /bridge-19.flac HTTP/1.1
[12:57:04.385362] handle_http:486 [0x6d3680]: HTTP headers
Host: 192.168.1.153
Connection: close
User-Agent: SignalystHQPlayer/4
Accept: */*

[12:57:04.385387] handle_http:573 [0x6d3680]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac


[12:57:04.422269] read_line:589 fd: 14 read error: 104 Connection reset by peer
[12:57:04.422311] http_parse:513 cannot read method
[12:57:04.422319] handle_http:478 [0x6d3680]: http parsing error (null)
[12:57:04.422326] output_http_thread:209 [0x6d3680]: HTTP close 14 (bytes 2457599) (n:1 res:0)
[12:57:04.528814] output_http_thread:157 [0x6d3680]: got HTTP connection 14
[12:57:04.529070] handle_http:483 [0x6d3680]: received HEAD /bridge-19.flac HTTP/1.1
[12:57:04.529087] handle_http:486 [0x6d3680]: HTTP headers
Host: 192.168.1.153
Connection: close
User-Agent: SignalystHQPlayer/4
Accept: */*

[12:57:04.529122] handle_http:573 [0x6d3680]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac


[12:57:04.529133] output_http_thread:209 [0x6d3680]: HTTP close 14 (bytes 2457599) (n:1 res:0)
[12:57:04.529322] output_http_thread:157 [0x6d3680]: got HTTP connection 14
[12:57:04.529551] handle_http:483 [0x6d3680]: received GET /bridge-19.flac HTTP/1.1
[12:57:04.529567] handle_http:486 [0x6d3680]: HTTP headers
Host: 192.168.1.153
Connection: close
User-Agent: SignalystHQPlayer/4
Accept: */*

[12:57:04.529577] handle_http:554 [0x6d3680]: re-opening a connection at 2457599
[12:57:04.529584] handle_http:556 [0x6d3680]: head is lost 2457599
[12:57:04.529613] handle_http:573 [0x6d3680]: responding:
HTTP/1.1 200 OK
Server: squeezebox-bridge
Connection: close
Content-Type: audio/flac


[12:57:04.770708] _SyncNotifState:687 HQPlayerEmbedded:SignalystHQPlayer4: uPNP playing
[12:57:04.770754] sq_notify:603 [0x6d3680] play ignored
[12:57:05.271125] sq_notify:715 [0x6d3680] track 19 started by info at 4258472

[Paul Webster]

By the way here a link of old problem.
Unixman posts but pc is mine

That was 4 years ago ... so I would expect some things to have changed since then.
Probably best to state what works now and what does not work and what you have tried recently.

[Riccardo]

That was 4 years ago ... so I would expect some things to have changed since then.
Probably best to state what works now and what does not work and what you have tried recently.

I'm really sorry but my long posts are submitted for approval by the moderators. I wrote 3 of them and unfortunately they were not published. I apologize Paul