Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

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

Open
christiscarborough opened this issue Feb 9, 2014 · 8 comments
Open

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

christiscarborough opened this issue Feb 9, 2014 · 8 comments

Comments

@christiscarborough
Copy link
Contributor

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
Copy link
Contributor Author

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
Copy link
Contributor Author

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
Copy link
Owner

hzeller commented Feb 24, 2014

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
Copy link
Contributor Author

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
Copy link
Contributor Author 

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
Copy link
Contributor Author

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

@christiscarborough
Copy link
Contributor Author

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
Copy link

AidanRB commented Dec 26, 2017

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.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@hzeller @christiscarborough @AidanRB