Stopping playback does not work

[foobarth]

I'm having a weird problem with a late build of gmrender on Arch Linux (built from AUR, version 301...), it just refuses to stop and plays on. I can see the stop command being received in the logfile, but it just doesn't stop anymore, regardless which UPnP client im using (BubbleUPnP, dlnap, ...).

Logfile snippet:

INFO  [2018-04-29 11:17:40.095676 | main] gmediarender 0.0.7-git log started [ gmediarender 2018-04-01_4f221e6 (libupnp-1.6.25; glib-2.54.3; gstreamer-1.14.0) ]
INFO  [2018-04-29 11:17:40.095718 | output] Using output module: gst (GStreamer multimedia framework)
INFO  [2018-05-05 09:28:06.203176 | transport] TransportState: PLAYING
INFO  [2018-05-05 09:28:06.203240 | transport] CurrentTransportActions: PAUSE,STOP,SEEK
INFO  [2018-05-05 09:28:06.203261 | transport] CurrentTrack: 1
INFO  [2018-05-05 09:28:06.203278 | transport] CurrentTrackURI: http://192.168.178.254:49200/ST/AUDIO/DLNA-1-0/rbb-radioeins-live.
cast.addradio.de/rbb/radioeins/live/mp3/128/stream.mp3
INFO  [2018-05-05 09:28:06.203293 | transport] CurrentTrackMetaData: <DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite
/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:dlna="urn:schemas-dlna-o
rg:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/" xmlns:pv="http://www.pv.com/pvns/"><item id="5:audio5:part13:3371:5" parentID=
"4:cont2:615:part12:42" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>radioeins Stream</dc:tit
le><dc:creator>radioeins</dc:creator><upnp:artist>radioeins</upnp:artist><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;
DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000">http://192.168.178.254:49200/ST/AUDIO/DLNA-1-0/rbb-r
adioeins-live.cast.addradio.de/rbb/radioeins/live/mp3/128/stream.mp3</res></item></DIDL-Lite>
INFO  [2018-05-05 09:28:06.203532 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<TransportState val="PLAYING"></TransportState>
<CurrentTransportActions val="PAUSE,STOP,SEEK"></CurrentTransportActions>
<CurrentTrack val="1"></CurrentTrack>
<CurrentTrackURI val="http://192.168.178.254:49200/ST/AUDIO/DLNA-1-0/rbb-radioeins-live.cast.addradio.de/rbb/radioeins/live/mp3/128/stream.mp3"></CurrentTrackURI>
<CurrentTrackMetaData val="&lt;DIDL-Lite xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&quot; xmlns:upnp=&quot;urn:schemas-upnp-org:metadata-1-0/upnp/&quot; xmlns:dc=&quot;http://purl.org/dc/elements/1.1/&quot; xmlns:dlna=&quot;urn:schemas-dlna-org:metadata-1-0/&quot; xmlns:sec=&quot;http://www.sec.co.kr/&quot; xmlns:pv=&quot;http://www.pv.com/pvns/&quot;&gt;&lt;item id=&quot;5:audio5:part13:3371:5&quot; parentID=&quot;4:cont2:615:part12:42&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;radioeins Stream&lt;/dc:title&gt;&lt;dc:creator&gt;radioeins&lt;/dc:creator&gt;&lt;upnp:artist&gt;radioeins&lt;/upnp:artist&gt;&lt;res protocolInfo=&quot;http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000&quot;&gt;http://192.168.178.254:49200/ST/AUDIO/DLNA-1-0/rbb-radioeins-live.cast.addradio.de/rbb/radioeins/live/mp3/128/stream.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></CurrentTrackMetaData>
</InstanceID>
</Event>
INFO  [2018-05-05 09:28:08.089182 | transport] RelativeTimePosition: 0:00:01
INFO  [2018-05-05 09:28:09.089877 | transport] RelativeTimePosition: 0:00:02
INFO  [2018-05-05 09:28:10.090628 | transport] RelativeTimePosition: 0:00:03
INFO  [2018-05-05 09:28:11.091470 | transport] RelativeTimePosition: 0:00:04
INFO  [2018-05-05 09:28:12.092324 | transport] RelativeTimePosition: 0:00:05
INFO  [2018-05-05 09:28:12.676854 | transport] TransportState: STOPPED
INFO  [2018-05-05 09:28:12.676904 | transport] CurrentTransportActions: PLAY,SEEK
INFO  [2018-05-05 09:28:12.677011 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<TransportState val="STOPPED"></TransportState>
<CurrentTransportActions val="PLAY,SEEK"></CurrentTransportActions>
</InstanceID>
</Event>
INFO  [2018-05-05 09:28:13.093196 | transport] RelativeTimePosition: 0:00:00
INFO  [2018-05-05 09:28:14.094042 | transport] RelativeTimePosition: 0:00:01
INFO  [2018-05-05 09:28:15.094710 | transport] RelativeTimePosition: 0:00:02
INFO  [2018-05-05 09:28:16.095558 | transport] RelativeTimePosition: 0:00:03
INFO  [2018-05-05 09:28:17.096301 | transport] RelativeTimePosition: 0:00:04
INFO  [2018-05-05 09:28:18.097126 | transport] RelativeTimePosition: 0:00:05
INFO  [2018-05-05 09:28:19.097993 | transport] RelativeTimePosition: 0:00:06
INFO  [2018-05-05 09:28:20.098853 | transport] RelativeTimePosition: 0:00:07
INFO  [2018-05-05 09:28:21.099708 | transport] RelativeTimePosition: 0:00:08
INFO  [2018-05-05 09:28:22.100539 | transport] RelativeTimePosition: 0:00:09
INFO  [2018-05-05 09:28:23.101333 | transport] RelativeTimePosition: 0:00:10
INFO  [2018-05-05 09:28:24.102175 | transport] RelativeTimePosition: 0:00:11
INFO  [2018-05-05 09:28:24.639319 | main] Exiting.

Any hint on how i can debug this?

[randombop]

I just updated and get the same behaviour - somehow it's broken the buffering patch.
Switch off buffering will help: --gstout-buffer-duration=0
If you know about gstreamer and buffering, you can try to fix it in output_gstreamer.c

[foobarth]

Thanks for the hint, that'll do as a temporary workaround! Unfortunately i know too less about GStreamer and C to be of any real help here (other than debugging).

[hzeller]

Buffering is now disabled by default (also see #182 ).