search

hzeller / gmrender-resurrect

Resource efficient UPnP/DLNA renderer, optimal for Raspberry Pi, CuBox or a general MediaServer. Fork of GMediaRenderer to add some features to make it usable.
GNU General Public License v2.0
834 stars 202 forks source link

renderer stops being able to play tracks when selected after a while #55

Open christiscarborough opened 10 years ago

christiscarborough commented 10 years ago

I find that I usually have to restart gmrender after a while because of this problem. It will take commands to play new tracks but the track remains at 0:00 in the control point and does not play.

I have reproduced this with gstreamer versions < 1 and gstreamer 1.2.2 on the RasPi running raspbian.

I can reproduce this bug by frequently changing tracks until it manifests. I am happy to help with further debugging.

christiscarborough commented 10 years ago

Here is a dump of the log from the time a new track is selected in the control point after the bug has manifested:

INFO  [2014-02-09 17:32:48.110140 | transport] TransportState: STOPPED
INFO  [2014-02-09 17:32:48.111411 | transport] CurrentTransportActions: PLAY,SEEK
INFO  [2014-02-09 17:32:48.115314 | 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  [2014-02-09 17:32:48.914205 | transport] AVTransportURI: http://192.168.37.71:8200/MediaItems/35.mp3
INFO  [2014-02-09 17:32:48.916280 | transport] AVTransportURIMetaData: <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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="64$0$0$0$8" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>You Spin Me Round (Like A Record)</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>80s Cheese Compilation</upnp:artist><upnp:albumArtURI>http://192.168.37.71:8200/AlbumArt/10-35.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2004-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>9</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="8091648" duration="0:03:21.000">http://192.168.37.71:8200/MediaItems/35.mp3</res></item></DIDL-Lite>
INFO  [2014-02-09 17:32:48.918305 | gstreamer] Set uri to 'http://192.168.37.71:8200/MediaItems/35.mp3'
INFO  [2014-02-09 17:32:48.920629 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<AVTransportURI val="http://192.168.37.71:8200/MediaItems/35.mp3"></AVTransportURI>
<AVTransportURIMetaData 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;&gt;&lt;item id=&quot;64$0$0$0$8&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;You Spin Me Round (Like A Record)&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;80s Cheese Compilation&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.71:8200/AlbumArt/10-35.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2004-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;9&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;8091648&quot; duration=&quot;0:03:21.000&quot;&gt;http://192.168.37.71:8200/MediaItems/35.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></AVTransportURIMetaData>
</InstanceID>
</Event>
INFO  [2014-02-09 17:32:49.163635 | transport] TransportState: PLAYING
INFO  [2014-02-09 17:32:49.164987 | transport] CurrentTransportActions: PAUSE,STOP,SEEK
INFO  [2014-02-09 17:32:49.166227 | transport] CurrentTrackURI: http://192.168.37.71:8200/MediaItems/35.mp3
INFO  [2014-02-09 17:32:49.167505 | 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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="64$0$0$0$8" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>You Spin Me Round (Like A Record)</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>80s Cheese Compilation</upnp:artist><upnp:albumArtURI>http://192.168.37.71:8200/AlbumArt/10-35.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2004-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>9</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="8091648" duration="0:03:21.000">http://192.168.37.71:8200/MediaItems/35.mp3</res></item></DIDL-Lite>
INFO  [2014-02-09 17:32:49.170396 | 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>
<CurrentTrackURI val="http://192.168.37.71:8200/MediaItems/35.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;&gt;&lt;item id=&quot;64$0$0$0$8&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;You Spin Me Round (Like A Record)&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;80s Cheese Compilation&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.71:8200/AlbumArt/10-35.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2004-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;9&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;8091648&quot; duration=&quot;0:03:21.000&quot;&gt;http://192.168.37.71:8200/MediaItems/35.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></CurrentTrackMetaData>
</InstanceID>
</Event>
INFO  [2014-02-09 17:32:50.441467 | transport] AVTransportURIMetaData: <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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="gmr-0000003f" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>99 Luftballons</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>Nena</upnp:artist><upnp:albumArtURI>http://192.168.37.71:8200/AlbumArt/10-35.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2004-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>9</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="8091648" duration="0:03:21.000">http://192.168.37.71:8200/MediaItems/35.mp3</res></item></DIDL-Lite>
INFO  [2014-02-09 17:32:50.443033 | 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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="gmr-0000003f" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>99 Luftballons</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>Nena</upnp:artist><upnp:albumArtURI>http://192.168.37.71:8200/AlbumArt/10-35.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2004-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>9</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="8091648" duration="0:03:21.000">http://192.168.37.71:8200/MediaItems/35.mp3</res></item></DIDL-Lite>
INFO  [2014-02-09 17:32:50.445576 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<AVTransportURIMetaData 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;&gt;&lt;item id=&quot;gmr-0000003f&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;99 Luftballons&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;Nena&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.71:8200/AlbumArt/10-35.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2004-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;9&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;8091648&quot; duration=&quot;0:03:21.000&quot;&gt;http://192.168.37.71:8200/MediaItems/35.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></AVTransportURIMetaData>
<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;&gt;&lt;item id=&quot;gmr-0000003f&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;99 Luftballons&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;Nena&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.71:8200/AlbumArt/10-35.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2004-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;9&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;8091648&quot; duration=&quot;0:03:21.000&quot;&gt;http://192.168.37.71:8200/MediaItems/35.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></CurrentTrackMetaData>
</InstanceID>
</Event>
INFO  [2014-02-09 17:32:50.455338 | transport] AVTransportURIMetaData: <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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="gmr-00000040" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>You Spin Me Round (Like A Record)</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>Dead Or Alive</upnp:artist><upnp:albumArtURI>http://192.168.37.71:8200/AlbumArt/10-35.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2004-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>9</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="8091648" duration="0:03:21.000">http://192.168.37.71:8200/MediaItems/35.mp3</res></item></DIDL-Lite>
INFO  [2014-02-09 17:32:50.463352 | 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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="gmr-00000040" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>You Spin Me Round (Like A Record)</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>Dead Or Alive</upnp:artist><upnp:albumArtURI>http://192.168.37.71:8200/AlbumArt/10-35.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2004-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>9</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="8091648" duration="0:03:21.000">http://192.168.37.71:8200/MediaItems/35.mp3</res></item></DIDL-Lite>
INFO  [2014-02-09 17:32:50.469609 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<AVTransportURIMetaData 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;&gt;&lt;item id=&quot;gmr-00000040&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;You Spin Me Round (Like A Record)&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;Dead Or Alive&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.71:8200/AlbumArt/10-35.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2004-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;9&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;8091648&quot; duration=&quot;0:03:21.000&quot;&gt;http://192.168.37.71:8200/MediaItems/35.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></AVTransportURIMetaData>
<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;&gt;&lt;item id=&quot;gmr-00000040&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;You Spin Me Round (Like A Record)&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;Dead Or Alive&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.71:8200/AlbumArt/10-35.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2004-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;9&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;8091648&quot; duration=&quot;0:03:21.000&quot;&gt;http://192.168.37.71:8200/MediaItems/35.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></CurrentTrackMetaData>
</InstanceID>
</Event>
ERROR [2014-02-09 17:32:50.473047 | gstreamer] openalsink1: Error: The stream is in the wrong format. (Debug: gstaudiobasesink.c(1105): gst_audio_base_sink_preroll (): /GstPlayBin:play/GstPlaySink:playsink/GstBin:abin/GstOpenALSink:openalsink1:
sink not negotiated.)
INFO  [2014-02-09 17:32:50.987257 | gstreamer] Set next uri to 'http://192.168.37.71:8200/MediaItems/36.mp3'
INFO  [2014-02-09 17:32:50.990889 | transport] NextAVTransportURI: http://192.168.37.71:8200/MediaItems/36.mp3
INFO  [2014-02-09 17:32:50.995363 | transport] NextAVTransportURIMetaData: <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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="64$0$0$0$9" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>Down Under</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>80s Cheese Compilation</upnp:artist><upnp:albumArtURI>http://192.168.37.71:8200/AlbumArt/11-36.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2004-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>10</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="8859648" duration="0:03:40.000">http://192.168.37.71:8200/MediaItems/36.mp3</res></item></DIDL-Lite>
INFO  [2014-02-09 17:32:51.008340 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<NextAVTransportURI val="http://192.168.37.71:8200/MediaItems/36.mp3"></NextAVTransportURI>
<NextAVTransportURIMetaData 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;&gt;&lt;item id=&quot;64$0$0$0$9&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;Down Under&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;80s Cheese Compilation&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.71:8200/AlbumArt/11-36.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2004-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;10&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;8859648&quot; duration=&quot;0:03:40.000&quot;&gt;http://192.168.37.71:8200/MediaItems/36.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></NextAVTransportURIMetaData>
</InstanceID>
</Event>
christiscarborough commented 10 years ago

Any clue as to what might be causing this? I've had independent verification of it as an issue, and it's the only showstopper bug on RasPi I'm aware of.

hzeller commented 10 years ago

Clarification on how to manifest it: rapidly switching between STOP and PLAY on one song until it permanently stops ?

(As for the logfile above: it is missing the XML encode as this is all going through the github markdown converter. So you need to add three backticks around the logfile-paste https://help.github.com/articles/github-flavored-markdown#fenced-code-blocks )

christiscarborough commented 10 years ago

Hi,

I actually had some difficulty in reproducing this again. By changing a track I meant "repeatedly selecting different tracks on the control point". I think it may be that there is a random chance of this failing each time it happens, and it's possibly more than one bug, which is going to make it tricky to track down. In any case, I've now got a log I will upload below. However, the interesting lines from both logs seem to be:

ERROR [2014-02-28 15:07:05.412546 | gstreamer] play: Error: Internal playbin error. (Debug: gstplaybin2.c(3421): no_more_pads_cb (): /GstPlayBin:play: Failed to link combiner to sink. Error -6)

and from the earlier failure above

ERROR [2014-02-09 17:32:50.473047 | gstreamer] openalsink1: Error: The stream is in the wrong format. (Debug: gstaudiobasesink.c(1105): gst_audio_base_sink_preroll (): /GstPlayBin:play/GstPlaySink:playsink/GstBin:abin/GstOpenALSink:openalsink1: sink not negotiated.)

So, it looks as though gmrender is losing it's connection to the sink in both cases.

I have also edited my earlier post to fix the formatting problem and display the original log correctly.

christiscarborough commented 10 years ago 
INFO  [2014-02-28 15:07:04.797172 | transport] TransportState: STOPPED
INFO  [2014-02-28 15:07:04.799194 | transport] CurrentTransportActions: PLAY,SEEK
INFO  [2014-02-28 15:07:04.801497 | 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  [2014-02-28 15:07:05.030126 | transport] AVTransportURI: http://192.168.37.90:8200/MediaItems/32.mp3
INFO  [2014-02-28 15:07:05.032197 | transport] AVTransportURIMetaData: <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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="64$0$0$0$5" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>Take My Breath Away</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>80s Cheese Compilation</upnp:artist><upnp:albumArtURI>http://192.168.37.90:8200/AlbumArt/7-32.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2007-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>6</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="128000" sampleFrequency="44100" nrAudioChannels="2" size="6334464" duration="0:04:24.000">http://192.168.37.90:8200/MediaItems/32.mp3</res></item></DIDL-Lite>
INFO  [2014-02-28 15:07:05.034418 | gstreamer] Set uri to 'http://192.168.37.90:8200/MediaItems/32.mp3'
INFO  [2014-02-28 15:07:05.037678 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<AVTransportURI val="http://192.168.37.90:8200/MediaItems/32.mp3"></AVTransportURI>
<AVTransportURIMetaData 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;&gt;&lt;item id=&quot;64$0$0$0$5&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;Take My Breath Away&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;80s Cheese Compilation&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.90:8200/AlbumArt/7-32.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2007-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;6&lt;/upnp:originalTrackNumber&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; bitrate=&quot;128000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;6334464&quot; duration=&quot;0:04:24.000&quot;&gt;http://192.168.37.90:8200/MediaItems/32.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></AVTransportURIMetaData>
</InstanceID>
</Event>
INFO  [2014-02-28 15:07:05.191891 | transport] TransportState: PLAYING
INFO  [2014-02-28 15:07:05.193982 | transport] CurrentTransportActions: PAUSE,STOP,SEEK
INFO  [2014-02-28 15:07:05.195877 | transport] CurrentTrackURI: http://192.168.37.90:8200/MediaItems/32.mp3
INFO  [2014-02-28 15:07:05.197962 | 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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="64$0$0$0$5" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>Take My Breath Away</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>80s Cheese Compilation</upnp:artist><upnp:albumArtURI>http://192.168.37.90:8200/AlbumArt/7-32.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2007-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>6</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="128000" sampleFrequency="44100" nrAudioChannels="2" size="6334464" duration="0:04:24.000">http://192.168.37.90:8200/MediaItems/32.mp3</res></item></DIDL-Lite>
INFO  [2014-02-28 15:07:05.200662 | 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>
<CurrentTrackURI val="http://192.168.37.90:8200/MediaItems/32.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;&gt;&lt;item id=&quot;64$0$0$0$5&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;Take My Breath Away&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;80s Cheese Compilation&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.90:8200/AlbumArt/7-32.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2007-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;6&lt;/upnp:originalTrackNumber&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; bitrate=&quot;128000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;6334464&quot; duration=&quot;0:04:24.000&quot;&gt;http://192.168.37.90:8200/MediaItems/32.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></CurrentTrackMetaData>
</InstanceID>
</Event>
ERROR [2014-02-28 15:07:05.412546 | gstreamer] play: Error: Internal playbin error. (Debug: gstplaybin2.c(3421): no_more_pads_cb (): /GstPlayBin:play:
Failed to link combiner to sink. Error -6)
INFO  [2014-02-28 15:07:07.152325 | gstreamer] Set next uri to 'http://192.168.37.90:8200/MediaItems/33.mp3'
INFO  [2014-02-28 15:07:07.154354 | transport] NextAVTransportURI: http://192.168.37.90:8200/MediaItems/33.mp3
INFO  [2014-02-28 15:07:07.156349 | transport] NextAVTransportURIMetaData: <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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="64$0$0$0$6" parentID="64$0$0$0" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>The Final Countdown</dc:title><dc:creator>80s Cheese Compilation</dc:creator><upnp:artist>80s Cheese Compilation</upnp:artist><upnp:albumArtURI>http://192.168.37.90:8200/AlbumArt/8-33.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2004-01-01</dc:date><upnp:album>80s Cheese Compilation</upnp:album><upnp:originalTrackNumber>7</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="12492800" duration="0:05:11.000">http://192.168.37.90:8200/MediaItems/33.mp3</res></item></DIDL-Lite>
INFO  [2014-02-28 15:07:07.159663 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<NextAVTransportURI val="http://192.168.37.90:8200/MediaItems/33.mp3"></NextAVTransportURI>
<NextAVTransportURIMetaData 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;&gt;&lt;item id=&quot;64$0$0$0$6&quot; parentID=&quot;64$0$0$0&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;The Final Countdown&lt;/dc:title&gt;&lt;dc:creator&gt;80s Cheese Compilation&lt;/dc:creator&gt;&lt;upnp:artist&gt;80s Cheese Compilation&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.90:8200/AlbumArt/8-33.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2004-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;80s Cheese Compilation&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;7&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;12492800&quot; duration=&quot;0:05:11.000&quot;&gt;http://192.168.37.90:8200/MediaItems/33.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></NextAVTransportURIMetaData>
</InstanceID>
</Event>
christiscarborough commented 10 years ago

Any progress on tracking this down? Is there anything else I can do to help?

christiscarborough commented 10 years ago

I've been leaving logging running to try to track this down. Here's my latest log taken from a live system where I was doing nothing unusual. The previous album ended, I queued up a new album, and got silence. I can see no reason why this might happen.

INFO  [2014-03-15 16:17:53.330449 | gstreamer] about-to-finish cb: setting uri (null)
INFO  [2014-03-15 16:17:54.118742 | transport] RelativeTimePosition: 0:02:51
INFO  [2014-03-15 16:17:54.589074 | gstreamer] play: End-of-stream
INFO  [2014-03-15 16:17:54.590348 | transport] AVTransportURI:
INFO  [2014-03-15 16:17:54.591606 | transport] AVTransportURIMetaData:
INFO  [2014-03-15 16:17:54.592820 | transport] NumberOfTracks: 0
INFO  [2014-03-15 16:17:54.594037 | transport] CurrentTrack: 0
INFO  [2014-03-15 16:17:54.595214 | transport] CurrentTrackURI:
INFO  [2014-03-15 16:17:54.596427 | transport] CurrentTrackMetaData:
INFO  [2014-03-15 16:17:54.597622 | transport] TransportState: STOPPED
INFO  [2014-03-15 16:17:54.599409 | transport] CurrentTransportActions: PLAY
INFO  [2014-03-15 16:17:54.600951 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<AVTransportURI val=""></AVTransportURI>
<AVTransportURIMetaData val=""></AVTransportURIMetaData>
<NumberOfTracks val="0"></NumberOfTracks>
<CurrentTrack val="0"></CurrentTrack>
<CurrentTrackURI val=""></CurrentTrackURI>
<CurrentTrackMetaData val=""></CurrentTrackMetaData>
<TransportState val="STOPPED"></TransportState>
<CurrentTransportActions val="PLAY"></CurrentTransportActions>
</InstanceID>
</Event>
INFO  [2014-03-15 16:21:27.502715 | gstreamer] Set next uri to 'http://192.168.37.90:8200/MediaItems/3160.mp3'
INFO  [2014-03-15 16:21:27.504107 | transport] NextAVTransportURI: http://192.168.37.90:8200/MediaItems/3160.mp3
INFO  [2014-03-15 16:21:27.505443 | transport] NextAVTransportURIMetaData: <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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="1$6$49$1$0" parentID="1$6$49$1" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>So Sorry</dc:title><dc:creator>Feist</dc:creator><upnp:artist>Feist</upnp:artist><upnp:albumArtURI>http://192.168.37.90:8200/AlbumArt/2412-3160.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2012-01-01</dc:date><upnp:album>The Reminder</upnp:album><upnp:originalTrackNumber>1</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="7745424" duration="0:03:11.000">http://192.168.37.90:8200/MediaItems/3160.mp3</res></item></DIDL-Lite>
INFO  [2014-03-15 16:21:27.510388 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<NextAVTransportURI val="http://192.168.37.90:8200/MediaItems/3160.mp3"></NextAVTransportURI>
<NextAVTransportURIMetaData 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;&gt;&lt;item id=&quot;1$6$49$1$0&quot; parentID=&quot;1$6$49$1&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;So Sorry&lt;/dc:title&gt;&lt;dc:creator&gt;Feist&lt;/dc:creator&gt;&lt;upnp:artist&gt;Feist&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.90:8200/AlbumArt/2412-3160.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2012-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;The Reminder&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;1&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;7745424&quot; duration=&quot;0:03:11.000&quot;&gt;http://192.168.37.90:8200/MediaItems/3160.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></NextAVTransportURIMetaData>
</InstanceID>
</Event>
INFO  [2014-03-15 16:21:27.640365 | transport] AVTransportURI: http://192.168.37.90:8200/MediaItems/3160.mp3
INFO  [2014-03-15 16:21:27.641771 | transport] AVTransportURIMetaData: <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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="1$6$49$1$0" parentID="1$6$49$1" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>So Sorry</dc:title><dc:creator>Feist</dc:creator><upnp:artist>Feist</upnp:artist><upnp:albumArtURI>http://192.168.37.90:8200/AlbumArt/2412-3160.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2012-01-01</dc:date><upnp:album>The Reminder</upnp:album><upnp:originalTrackNumber>1</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="7745424" duration="0:03:11.000">http://192.168.37.90:8200/MediaItems/3160.mp3</res></item></DIDL-Lite>
INFO  [2014-03-15 16:21:27.643359 | transport] NumberOfTracks: 1
INFO  [2014-03-15 16:21:27.644554 | gstreamer] Set uri to 'http://192.168.37.90:8200/MediaItems/3160.mp3'
INFO  [2014-03-15 16:21:27.649744 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<AVTransportURI val="http://192.168.37.90:8200/MediaItems/3160.mp3"></AVTransportURI>
<AVTransportURIMetaData 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;&gt;&lt;item id=&quot;1$6$49$1$0&quot; parentID=&quot;1$6$49$1&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;So Sorry&lt;/dc:title&gt;&lt;dc:creator&gt;Feist&lt;/dc:creator&gt;&lt;upnp:artist&gt;Feist&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.90:8200/AlbumArt/2412-3160.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2012-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;The Reminder&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;1&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;7745424&quot; duration=&quot;0:03:11.000&quot;&gt;http://192.168.37.90:8200/MediaItems/3160.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></AVTransportURIMetaData>
<NumberOfTracks val="1"></NumberOfTracks>
</InstanceID>
</Event>
INFO  [2014-03-15 16:21:27.779795 | transport] RelativeTimePosition: 0:00:00
INFO  [2014-03-15 16:21:27.887846 | transport] TransportState: PLAYING
INFO  [2014-03-15 16:21:27.889356 | transport] CurrentTransportActions: PAUSE,STOP,SEEK
INFO  [2014-03-15 16:21:27.890584 | transport] CurrentTrack: 1
INFO  [2014-03-15 16:21:27.891783 | transport] CurrentTrackURI: http://192.168.37.90:8200/MediaItems/3160.mp3
INFO  [2014-03-15 16:21:27.893035 | 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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="1$6$49$1$0" parentID="1$6$49$1" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>So Sorry</dc:title><dc:creator>Feist</dc:creator><upnp:artist>Feist</upnp:artist><upnp:albumArtURI>http://192.168.37.90:8200/AlbumArt/2412-3160.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2012-01-01</dc:date><upnp:album>The Reminder</upnp:album><upnp:originalTrackNumber>1</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="7745424" duration="0:03:11.000">http://192.168.37.90:8200/MediaItems/3160.mp3</res></item></DIDL-Lite>
INFO  [2014-03-15 16:21:27.895716 | 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.37.90:8200/MediaItems/3160.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;&gt;&lt;item id=&quot;1$6$49$1$0&quot; parentID=&quot;1$6$49$1&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;So Sorry&lt;/dc:title&gt;&lt;dc:creator&gt;Feist&lt;/dc:creator&gt;&lt;upnp:artist&gt;Feist&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.90:8200/AlbumArt/2412-3160.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2012-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;The Reminder&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;1&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;7745424&quot; duration=&quot;0:03:11.000&quot;&gt;http://192.168.37.90:8200/MediaItems/3160.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></CurrentTrackMetaData>
</InstanceID>
</Event>
INFO  [2014-03-15 16:21:27.970414 | gstreamer] Set next uri to 'http://192.168.37.90:8200/MediaItems/3164.mp3'
INFO  [2014-03-15 16:21:27.972202 | transport] NextAVTransportURI: http://192.168.37.90:8200/MediaItems/3164.mp3
INFO  [2014-03-15 16:21:27.977395 | transport] NextAVTransportURIMetaData: <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-org:metadata-1-0/" xmlns:sec="http://www.sec.co.kr/"><item id="1$6$49$1$1" parentID="1$6$49$1" restricted="1"><upnp:class>object.item.audioItem.musicTrack</upnp:class><dc:title>I Feel It All</dc:title><dc:creator>Feist</dc:creator><upnp:artist>Feist</upnp:artist><upnp:albumArtURI>http://192.168.37.90:8200/AlbumArt/2413-3164.jpg</upnp:albumArtURI><upnp:genre>Pop</upnp:genre><dc:date>2012-01-01</dc:date><upnp:album>The Reminder</upnp:album><upnp:originalTrackNumber>2</upnp:originalTrackNumber><res protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01700000000000000000000000000000" bitrate="320000" sampleFrequency="44100" nrAudioChannels="2" size="8853026" duration="0:03:39.000">http://192.168.37.90:8200/MediaItems/3164.mp3</res></item></DIDL-Lite>
INFO  [2014-03-15 16:21:27.992423 | transport] LastChange: <?xml version="1.0"?>
<Event xmlns="urn:schemas-upnp-org:metadata-1-0/AVT/">
<InstanceID val="0">
<NextAVTransportURI val="http://192.168.37.90:8200/MediaItems/3164.mp3"></NextAVTransportURI>
<NextAVTransportURIMetaData 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;&gt;&lt;item id=&quot;1$6$49$1$1&quot; parentID=&quot;1$6$49$1&quot; restricted=&quot;1&quot;&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:title&gt;I Feel It All&lt;/dc:title&gt;&lt;dc:creator&gt;Feist&lt;/dc:creator&gt;&lt;upnp:artist&gt;Feist&lt;/upnp:artist&gt;&lt;upnp:albumArtURI&gt;http://192.168.37.90:8200/AlbumArt/2413-3164.jpg&lt;/upnp:albumArtURI&gt;&lt;upnp:genre&gt;Pop&lt;/upnp:genre&gt;&lt;dc:date&gt;2012-01-01&lt;/dc:date&gt;&lt;upnp:album&gt;The Reminder&lt;/upnp:album&gt;&lt;upnp:originalTrackNumber&gt;2&lt;/upnp:originalTrackNumber&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; bitrate=&quot;320000&quot; sampleFrequency=&quot;44100&quot; nrAudioChannels=&quot;2&quot; size=&quot;8853026&quot; duration=&quot;0:03:39.000&quot;&gt;http://192.168.37.90:8200/MediaItems/3164.mp3&lt;/res&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;"></NextAVTransportURIMetaData>
</InstanceID>
</Event>
ERROR [2014-03-15 16:21:28.681206 | gstreamer] openalsink1: Error: Unable to prepare device. (Debug: gstopenalsink.c(857): gst_openal_sink_prepare (): /GstPlayBin:play/GstPlaySink:playsink/GstBin:abin/GstOpenALSink:openalsink1:
ALC error: Invalid Device)
ERROR [2014-03-15 16:21:28.689803 | gstreamer] openalsink1: Error: The stream is in the wrong format. (Debug: gstaudiobasesink.c(1105): gst_audio_base_sink_preroll (): /GstPlayBin:play/GstPlaySink:playsink/GstBin:abin/GstOpenALSink:openalsink1:
sink not negotiated.)
AidanRB commented 6 years ago

To anyone in the future who finds this bug after googling error codes for fifteen minutes, I had the same problem; it turns out I didn't have pulseaudio itself installed. sudo apt install pulseaudio fixed it for me.