Hi!
I am having issues to get the plugin to work with my Denon AVR.
My server:
- Raspberry PI 3b on Raspbian Buster
- LMS 8.1.0
- UPNP Bridge 1.45.0
My Player: Denon AVR S750H
The player is able to receive UPnP/DLNA streams, I have tested this with
Bubble UPnP on my Android Phone and with Windows Media Player on my Win
10 device. I can start / stop playback, control the volume over UPnP and
the devices automatically switches on, as soon as I start playback.
My Issue:
In LMS / UPnP Bridge the player is also detected and shown under as a
player. It seems like the connection basically works, because the volume
changes in LMS are instantly synced to the AVR. Unfortunately the
playback does not work. When I press the "play" button, LMS shows
"playing" for 1s and then stops it. On the AVR itself nothing happens.
What I tried so far:
I already applied the "Denon" profile for the player and restarted the
pugin. I also tried the "_safe_mode" profile and played and some manual
configurations. But nothing got the player to work.
My Logs:
Code:
--------------------
Starting Squeeze2upnp:
/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/UPnPBridge/Bin/squeeze2upnp-armv6hf
-Z -I -b 192.168.178.102 -f /var/log/squeezeboxserver/upnpbridge.log -d
all=debug -x /var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:08:44.775795] main:1756 Starting squeeze2upnp version: v1.45.0 (Dec 27
2020 @ 14:23:57)
[12:08:44.793695] output_init:610 success loading FLAC encoder
[12:08:44.793875] register_alac:550 using alac to decode alc
[12:08:44.795276] load_mad:411 loaded libmad.so.0
[12:08:44.795337] register_mad:433 using mad to decode mp3
[12:08:44.795789] load_faad:626 loaded libfaad.so.2
[12:08:44.795839] register_faad:647 using faad to decode aac
[12:08:44.797004] load_vorbis:325 loaded libvorbisfile.so.3
[12:08:44.797092] register_vorbis:346 using vorbis to decode ogg
[12:08:44.797136] register_pcm:339 using pcm
[12:08:44.797212] load_flac:292 loaded libFLAC.so.8
[12:08:44.797255] register_flac:313 using flac to decode flc
[12:08:44.797453] load_opus:251 dlerror: libopusfile.so.0: cannot open shared
object file: No such file or directory
[12:08:44.797544] register_m4a_thru:307 using mp4 to aac (ADTS)
[12:08:44.797587] register_flac_thru:247 using flac
[12:08:44.797628] register_thru:106 using thru
[12:08:44.797667] register_soxr:394 using soxr for resampling
[12:08:44.797711] Start:1518 Binding to 192.168.178.102:49152 (http:0)
[12:08:45.032535] AddMRDevice:1405 [0x138d00]: adding renderer
(wohnzimmer-heimkino)
[12:08:45.032705] GetProtocolInfo:359 [0x138d00]: uPNP GetProtocolInfo
[12:08:45.036157] XMLGetFirstDocumentItem:181 ixmlNode_getFirstChild(tmpNode)
returned (nil) (nil)
[12:08:45.036245] GetProtocolInfo:370 [0x138d00]: ProtocolInfo (null)
[12:08:45.036276] AddMRDevice:1426 [0x138d00] unable to get protocol info,
set <forced_mimetypes>
[12:08:45.036589] stream_thread_init:464 [0xb7e88] streambuf size: 1048576
[12:08:45.038712] MasterHandler:1036 [0x138d00]: subscribe success
[12:08:45.045305] output_thread_init:574 [0xb7e88] init output media renderer
[12:08:45.045498] decode_thread_init:168 [0xb7e88]: init decode
[12:08:45.045769] resample_init:345 [0xb7e88]: 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:08:45.045824] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:08:45.047646] discover_server:806 [0xb7e88] sending discovery
[12:08:45.048717] discover_server:838 [0xb7e88] got response from:
192.168.178.102:3483
[12:08:45.048827] slimproto:858 squeezelite [0xb7e88] <=> player [0x138d00]
[12:08:45.048858] slimproto:859 [0xb7e88] connecting to 192.168.178.102:3483
[12:08:45.050221] slimproto:889 [0xb7e88] connected
[12:08:45.050341] sendHELO:134 [0xb7e88] mac: bb:bb:78:4c:a0:a8
[12:08:45.050375] sendHELO:135 [0xb7e88] cap:
CanHTTPS=1,Model=squeezelite,ModelName=UPnPBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=48000,
[12:08:45.052757] process:500 [0xb7e88] strm
[12:08:45.052896] process_strm:241 [0xb7e88] strm command q
[12:08:45.052929] decode_flush:208 [0xb7e88]: decode flush
[12:08:45.052959] output_flush:569 [0xb7e88]: flush output buffer
[12:08:45.052990] sq_callback:314 [0x138d00]: device off or not controlled by
LMS
[12:08:45.053093] process:500 [0xb7e88] strm
[12:08:45.053124] process_strm:241 [0xb7e88] strm command q
[12:08:45.053152] decode_flush:208 [0xb7e88]: decode flush
[12:08:45.053180] output_flush:569 [0xb7e88]: flush output buffer
[12:08:45.053615] process:500 [0xb7e88] setd
[12:08:45.053652] sendSETDName:227 [8]: set playername: wohnzimmer-heimkino
[12:08:45.053850] process:500 [0xb7e88] setd
[12:08:45.054152] process:500 [0xb7e88] aude
[12:08:45.054192] process_aude:392 [0xb7e88] on/off using aude 1
[12:08:45.054223] sq_callback:310 [0x138d00]: device set on/off 1
[12:08:45.054545] process:500 [0xb7e88] audg
[12:08:45.054584] process_audg:406 [0xb7e88] (old) audg gainL: 10 gainR: 10
[12:08:45.054616] CtrlSetVolume:245 [0x138d00]: uPNP volume 17 (cookie (nil))
[12:08:46.000720] UpdateThread:1151 [0x138d00] UPnP keep alive:
wohnzimmer-heimkino
[12:08:46.202487] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:08:47.206450] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:08:49.002907] process:500 [0xb7e88] strm
[12:08:49.003127] process_strm:241 [0xb7e88] strm command t
[12:08:49.003195] sendSTAT:169 [0xb7e88]: STAT:[STMt] msplayed 0
[12:08:49.203217] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:08:54.003700] process:500 [0xb7e88] strm
[12:08:54.008642] process_strm:241 [0xb7e88] strm command t
[12:08:54.008748] sendSTAT:169 [0xb7e88]: STAT:[STMt] msplayed 0
[12:08:54.650647] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:08:59.003717] process:500 [0xb7e88] strm
[12:08:59.009962] process_strm:241 [0xb7e88] strm command t
[12:08:59.010115] sendSTAT:169 [0xb7e88]: STAT:[STMt] msplayed 0
[12:09:04.000206] UpdateThread:1092 Presence checking
[12:09:04.001690] process:500 [0xb7e88] strm
[12:09:04.001837] process_strm:241 [0xb7e88] strm command t
[12:09:04.001897] sendSTAT:169 [0xb7e88]: STAT:[STMt] msplayed 0
[12:09:04.154734] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:09:04.188695] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:09:04.201017] UpdateThread:1092 Presence checking
[12:09:05.001108] UpdateThread:1151 [0x138d00] UPnP keep alive:
wohnzimmer-heimkino
[12:09:05.478632] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:09:06.000544] UpdateThread:1151 [0x138d00] UPnP keep alive:
wohnzimmer-heimkino
[12:09:08.473875] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:09:09.003528] process:500 [0xb7e88] strm
[12:09:09.003723] process_strm:241 [0xb7e88] strm command t
[12:09:09.003785] sendSTAT:169 [0xb7e88]: STAT:[STMt] msplayed 0
[12:09:12.720430] UpdateThread:1255 Updating configuration
/var/lib/squeezeboxserver/prefs/upnpbridge.xml
[12:09:14.001678] process:500 [0xb7e88] strm
[12:09:14.001877] process_strm:241 [0xb7e88] strm command t
[12:09:14.001939] sendSTAT:169 [0xb7e88]: STAT:[STMt] msplayed 0
[12:09:19.001562] process:500 [0xb7e88] strm
[12:09:19.001786] process_strm:241 [0xb7e88] strm command t
[12:09:19.001855] sendSTAT:169 [0xb7e88]: STAT:[STMt] msplayed 0
[12:09:21.077511] process:500 [0xb7e88] strm
[12:09:21.077712] process_strm:241 [0xb7e88] strm command q
[12:09:21.077777] decode_flush:208 [0xb7e88]: decode flush
[12:09:21.077843] output_flush:569 [0xb7e88]: flush output buffer
[12:09:21.077907] AVTStop:217 [0x138d00]: uPNP stop (cookie 0x1)
[12:09:21.122235] ActionHandler:821 [0x138d00]: Waited action StopResponse
[12:09:24.000231] UpdateThread:1092 Presence checking
[12:09:24.001476] process:500 [0xb7e88] strm
[12:09:24.001626] process_strm:241 [0xb7e88] strm command t
[12:09:24.001693] sendSTAT:169 [0xb7e88]: STAT:[STMt] msplayed 0
[12:09:24.200999] UpdateThread:1092 Presence checking
[12:09:25.042863] _SyncNotifState:643 [0x138d00]: uPNP stop
[12:09:25.043070] sq_notify:664 [0xb7e88] notify STOP
[12:09:26.000914] UpdateThread:1151 [0x138d00] UPnP keep alive:
wohnzimmer-heimkino
[12:09:26.010323] UpdateThread:1151 [0x138d00] UPnP keep alive:
wohnzimmer-heimkino
--------------------
LMS:
Code:
--------------------
[21-01-10 12:09:21.5641] Slim::Player::Song::open (415) Error: Couldn't
create command line for mp3 playback for
[http://wdr-edge-2017-fra-lg-cdn.cast.addradio.de/wdr/1live/diggi/mp3/128/stream.mp3?ar-distributor=f0a1&_art=dj0yJmlwPTg3LjE3Ni41NC4yMiZpZD1pY3NjeGwtbzRjc3N5bGxiJnQ9MTYxMDM2MzM2MSZzPTc4NjZmMjljIzgzZmI4MmEwYjIyNDU3MTA2NjMyMDI5ODg5NjNlMTc5]
[21-01-10 12:09:21.5789] Slim::Player::Song::open (415) Error: Couldn't
create command line for mp3 playback for [http://addrad.io/4WRMH6]
--------------------
The stream itself works flawlessly on my Squeezelite Player and another
UPnP player in my network. I also tried streaming with Spotty, which
shows the same errors.
Any idea what I can do? Someone has already opened a similar issue on
github, where I also posted some information, but I think here is a
better place to discuss and get help in the first instance. I really
hope someone is able to help me, because I already spent hours on trying
around and nothing improved.
Thank you in advance.
------------------------------------------------------------------------
gnackwatschn's Profile: http://forums.slimdevices.com/member.php?userid=71199
View this thread: http://forums.slimdevices.com/showthread.php?t=103728
_______________________________________________
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins
