[Android Apps and Games] billyx1:
Length of video incorrect

Hello,

I have a really annoying problem with BubbleUPnP and (at least) MythTV: The length information of the videos changes during playback and is incorrect then. I finst thought it is pehaps because of the free version and bought the full version now, but it is the same problem.

If I start to playback a video BubbleUPnp it is e.g. 1:20:00. During the first seconds of playback the length changes to 1:02:05. This means I can't e.g. jump to the position 1:05:00 and even worser: The playback stops at 1:02:05 and I'm not able to watch the end of the video. XBMC says the video is 1:31:59 long.

Here the part of the log:

[AsyncTask #4 ] INFO - 01:01:05.237 - LibraryActivity$a : BrowseTask.doInBackground(): enter
[main ] INFO - 01:01:05.263 - ai : selected item changed:
[main ] INFO - 01:01:05.293 - AndroidUpnpService : playItem: main thread
[main ] INFO - 01:01:05.294 - AbstractRenderer : CSI:NY: Die zersägte Jungfrau: returning first supported resource
[main ] INFO - 01:01:05.296 - ai : selected item changed: CSI:NY: Die zersägte Jungfrau
[stop ] INFO - 01:01:05.300 - AndroidUpnpService$n : playItem: worker thread enter
[playItem ] INFO - 01:01:05.301 - a : UPnPAVRenderer.playItem: enter: CSI:NY: Die zersägte Jungfrau
[playItem ] INFO - 01:01:05.302 - a : UPnPAVRenderer.playItem: playing resource: http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z
[playItem ] INFO - 01:01:05.867 - a : waitForTransportState: got transport state: Stopped
[playItem ] INFO - 01:01:05.873 - a : UPnPAVRenderer.playItem: SetAVTransportURI metadata: <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="RecTv/0/item?ChanId=13060&amp;StartTime=2013-10-26T23:43:00Z" parentID="RecTv/0" restricted="1"><upnp:class>object.item.videoItem</upnp:class><dc:title>CSI:NY: Die zersägte Jungfrau</dc:title><dc:creator>[Unknown Author]</dc:creator><upnp:artist>[Unknown Author]</upnp:artist><upnp:albumArtURI>http://10.0.0.100:6544/Content/GetPreviewImage?ChanId=13060&amp;StartTime=2013-10-26T23:43:00Z&amp;Width=160</upnp:albumArtURI><upnp:genre>Film</upnp:genre><upnp:album>[Unknown Series]</upnp:album><dc:date>2013-10-26T23:43:00Z</dc:date><res protocolInfo="http-get:*:video/mpeg:DLNA.ORG_CI=0;DLNA.ORG_OP=01;DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_FLAGS=01500000000000000000000000000000" size="3098876380" duration="1:20:00.000">http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z</res></item></DIDL-Lite>
[playItem ] INFO - 01:01:09.802 - a : UPnPAVRenderer.playItem: waiting 100 ms...
[main ] INFO - 01:01:09.812 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): TransportState: STOPPED
[main ] INFO - 01:01:09.813 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): AVTransportURI: http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z
[main ] INFO - 01:01:09.814 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): AVTransportURIMetaData: <DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"><item><dc:title>CSI:NY: Die zersägte Jungfrau</dc:title><dc:creator>[Unknown Author]</dc:creator><res protocolInfo="http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_OP=01;DLNA.ORG_FLAGS=01500000000000000000000000000000" duration="01:20:00.000">http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z</res><upnp:class>object.item.videoItem</upnp:class></item></DIDL-Lite>
[main ] INFO - 01:01:09.815 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentMediaDuration: 01:20:00.000
[main ] INFO - 01:01:09.816 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): NumberOfTracks: 0
[main ] INFO - 01:01:09.817 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackURI: http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z
[main ] INFO - 01:01:09.818 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrack: 0
[main ] INFO - 01:01:09.819 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:20:00.000
[main ] INFO - 01:01:09.820 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTransportActions: Next,Previous,Play
[main ] INFO - 01:01:09.821 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackMetaData: <DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"><item><dc:title>CSI:NY: Die zersägte Jungfrau</dc:title><dc:creator>[Unknown Author]</dc:creator><res protocolInfo="http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_OP=01;DLNA.ORG_FLAGS=01500000000000000000000000000000" duration="01:20:00.000">http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z</res><upnp:class>object.item.videoItem</upnp:class></item></DIDL-Lite>
[main ] INFO - 01:01:09.849 - a : setGaplessTransportStateChange: false
[main ] WARNING - 01:01:09.860 - y : fixed badly encoded entity in XML
[main ] WARNING - 01:01:09.861 - DIDLLite : bad XML found: replaced DIDLLite prefix
[main ] INFO - 01:01:10.35 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentMediaDuration: 01:20:00.000
[main ] INFO - 01:01:10.37 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): NumberOfTracks: 1
[main ] INFO - 01:01:10.39 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackURI: http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z
[main ] INFO - 01:01:10.41 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrack: 1
[main ] INFO - 01:01:10.42 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackMetaData: <DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"><item><dc:title>CSI:NY: Die zersägte Jungfrau</dc:title><dc:creator>[Unknown Author]</dc:creator><res protocolInfo="http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_OP=01;DLNA.ORG_FLAGS=01500000000000000000000000000000" duration="01:20:00.000">http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z</res><upnp:class>object.item.videoItem</upnp:class></item></DIDL-Lite>
[main ] INFO - 01:01:10.260 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): TransportState: TRANSITIONING
[main ] INFO - 01:01:10.261 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:20:00.000
[main ] INFO - 01:01:10.262 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTransportActions: Next,Previous,Stop
[main ] INFO - 01:01:10.284 - a : setGaplessTransportStateChange: false
[main ] INFO - 01:01:10.286 - v : started task TimeTask
[main ] SEVERE - 01:01:10.287 - bm : watchdog: control point playback++: 1
[main ] WARNING - 01:01:10.300 - y : fixed badly encoded entity in XML
[main ] WARNING - 01:01:10.301 - DIDLLite : bad XML found: replaced DIDLLite prefix
[main ] INFO - 01:01:10.522 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:20:00.000
[AsyncTask #5 ] INFO - 01:01:11.852 - LibraryActivity$a : BrowseTask.doInBackground(): enter
[main ] INFO - 01:01:11.869 - ai : selected item changed:
[main ] INFO - 01:01:11.958 - AndroidUpnpService : playItem: main thread
[main ] INFO - 01:01:11.959 - AbstractRenderer : CSI:NY: Die zersägte Jungfrau: returning first supported resource
[main ] INFO - 01:01:11.961 - ai : selected item changed: CSI:NY: Die zersägte Jungfrau
[playItem ] WARNING - 01:01:15.129 - a : waitForTransportState: waiting for Playing timeouted
[playItem ] INFO - 01:01:15.130 - a : UPnPAVRenderer.playItem: exit
[playItem ] INFO - 01:01:15.131 - AndroidUpnpService$n : playItem: worker thread took 9830ms
[playItem ] INFO - 01:01:15.132 - AndroidUpnpService$n : playItem: worker thread enter
[playItem ] INFO - 01:01:15.134 - a : UPnPAVRenderer.playItem: enter: CSI:NY: Die zersägte Jungfrau
[playItem ] INFO - 01:01:15.136 - a : UPnPAVRenderer.playItem: playing resource: http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z
[AsyncTask #2 ] INFO - 01:01:15.139 - g : saved file /storage/emulated/0/Android/data/com.bubblesoft.android.bubbleupnp/playlists/recent.dpl ok: 4 items
[main ] INFO - 01:01:19.332 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): Volume: 17
[main ] WARNING - 01:01:19.334 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.335 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.336 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.337 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.337 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.338 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.339 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.340 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.341 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.342 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.343 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.344 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] WARNING - 01:01:19.345 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): ignoring duplicate LastChange value Volume: 80
[main ] INFO - 01:01:19.554 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTransportActions: Next,Previous,Seek,X_DLNA_SeekTime,Stop
[main ] INFO - 01:01:20.551 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): TransportState: STOPPED
[main ] INFO - 01:01:20.553 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTransportActions: Next,Previous,Seek,X_DLNA_SeekTime,Play
[Stop-run ] WARNING - 01:01:20.555 - d : HTTP request took a long time: 5414ms: http://10.0.0.38:53444/BEKO/MediaRenderer_AVTransport/control
[main ] INFO - 01:01:20.581 - a : setGaplessTransportStateChange: false
[main ] INFO - 01:01:20.582 - v : stopping task TimeTask
[main ] SEVERE - 01:01:20.583 - bm : watchdog: control point playback--: 0
[playItem ] INFO - 01:01:20.590 - a : waitForTransportState: got transport state: Stopped
[main ] WARNING - 01:01:20.599 - y : fixed badly encoded entity in XML
[playItem ] INFO - 01:01:20.599 - a : UPnPAVRenderer.playItem: SetAVTransportURI metadata: <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="RecTv/0/item?ChanId=13060&amp;StartTime=2013-10-26T23:43:00Z" parentID="RecTv/0" restricted="1"><upnp:class>object.item.videoItem</upnp:class><dc:title>CSI:NY: Die zersägte Jungfrau</dc:title><dc:creator>[Unknown Author]</dc:creator><upnp:artist>[Unknown Author]</upnp:artist><upnp:albumArtURI>http://10.0.0.100:6544/Content/GetPreviewImage?ChanId=13060&amp;StartTime=2013-10-26T23:43:00Z&amp;Width=160</upnp:albumArtURI><upnp:genre>Film</upnp:genre><upnp:album>[Unknown Series]</upnp:album><dc:date>2013-10-26T23:43:00Z</dc:date><res protocolInfo="http-get:*:video/mpeg:DLNA.ORG_CI=0;DLNA.ORG_OP=01;DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_FLAGS=01500000000000000000000000000000" size="3098876380" duration="1:20:00.000">http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z</res></item></DIDL-Lite>
[main ] WARNING - 01:01:20.600 - DIDLLite : bad XML found: replaced DIDLLite prefix
[main ] INFO - 01:01:20.835 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:20:00.000
[main ] INFO - 01:01:20.836 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTransportActions: Next,Previous,Play
[com.bubblesoft.a] INFO - 01:01:22.215 - m : I/O exception (com.bubblesoft.b.a.a.aa) caught when processing request: The target server failed to respond
[com.bubblesoft.a] INFO - 01:01:22.216 - m : Retrying request
[com.bubblesoft.a] INFO - 01:01:22.232 - m : I/O exception (com.bubblesoft.b.a.a.aa) caught when processing request: The target server failed to respond
[com.bubblesoft.a] INFO - 01:01:22.233 - m : Retrying request
[main ] INFO - 01:01:22.930 - LibraryActivity : onSaveInstanceState
[main ] INFO - 01:01:22.935 - LibraryActivity : onPause
[main ] INFO - 01:01:22.936 - fy : onPause
[main ] INFO - 01:01:22.937 - AndroidUpnpService : removed listener: 0 listeners
[main ] INFO - 01:01:22.938 - NowPlayingActivity : onResume
[main ] INFO - 01:01:22.938 - fy : onResume
[main ] INFO - 01:01:22.945 - AndroidUpnpService : added listener: 1 listeners
[main ] INFO - 01:01:22.966 - AndroidUpnpService : registered media button event receiver
[main ] INFO - 01:01:22.967 - NowPlayingActivity : source changed: Playlist
[main ] INFO - 01:01:22.968 - hu : selected item changed: CSI:NY: Die zersägte Jungfrau
[playItem ] INFO - 01:01:23.03 - a : UPnPAVRenderer.playItem: waiting 100 ms...
[main ] INFO - 01:01:23.48 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackURI: http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z
[main ] INFO - 01:01:23.49 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrack: 0
[main ] INFO - 01:01:23.50 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:20:00.000
[main ] INFO - 01:01:23.52 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackMetaData: <DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"><item><dc:title>CSI:NY: Die zersägte Jungfrau</dc:title><dc:creator>[Unknown Author]</dc:creator><res protocolInfo="http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_OP=01;DLNA.ORG_FLAGS=01500000000000000000000000000000" duration="01:20:00.000">http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z</res><upnp:class>object.item.videoItem</upnp:class></item></DIDL-Lite>
[main ] INFO - 01:01:23.280 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackURI: http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z
[main ] INFO - 01:01:23.281 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrack: 1
[main ] INFO - 01:01:23.283 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackMetaData: <DIDL-Lite xmlns="urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/" xmlns:dc="http://purl.org/dc/elements/1.1/" xmlns:upnp="urn:schemas-upnp-org:metadata-1-0/upnp/"><item><dc:title>CSI:NY: Die zersägte Jungfrau</dc:title><dc:creator>[Unknown Author]</dc:creator><res protocolInfo="http-get:*:video/mpeg:DLNA.ORG_PN=MPEG_TS_SD_NA_ISO;DLNA.ORG_OP=01;DLNA.ORG_FLAGS=01500000000000000000000000000000" duration="01:20:00.000">http://10.0.0.100:6544/Content/GetRecording?ChanId=13060&StartTime=2013-10-26T23:43:00Z</res><upnp:class>object.item.videoItem</upnp:class></item></DIDL-Lite>
[main ] INFO - 01:01:23.617 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): TransportState: TRANSITIONING
[main ] INFO - 01:01:23.619 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTransportActions: Next,Previous,Stop
[main ] INFO - 01:01:23.648 - a : setGaplessTransportStateChange: false
[main ] INFO - 01:01:23.649 - v : started task TimeTask
[main ] SEVERE - 01:01:23.651 - bm : watchdog: control point playback++: 1
[main ] WARNING - 01:01:23.666 - y : fixed badly encoded entity in XML
[main ] WARNING - 01:01:23.668 - DIDLLite : bad XML found: replaced DIDLLite prefix
[main ] INFO - 01:01:23.901 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:20:00.000
[playItem ] WARNING - 01:01:28.219 - a : waitForTransportState: waiting for Playing timeouted
[playItem ] INFO - 01:01:28.220 - a : UPnPAVRenderer.playItem: exit
[playItem ] INFO - 01:01:28.221 - AndroidUpnpService$n : playItem: worker thread took 13087ms
[AsyncTask #3 ] INFO - 01:01:28.232 - g : saved file /storage/emulated/0/Android/data/com.bubblesoft.android.bubbleupnp/playlists/recent.dpl ok: 4 items
[main ] INFO - 01:01:30.390 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): TransportState: PLAYING
[main ] INFO - 01:01:30.392 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTransportActions: Next,Previous,Play,Stop,Pause
[main ] INFO - 01:01:30.520 - a : setGaplessTransportStateChange: false
[main ] WARNING - 01:01:30.541 - y : fixed badly encoded entity in XML
[main ] WARNING - 01:01:30.542 - DIDLLite : bad XML found: replaced DIDLLite prefix
[main ] INFO - 01:01:30.715 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:20:00.000
[main ] INFO - 01:01:30.716 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTransportActions: Next,Previous,Seek,X_DLNA_SeekTime,Play,Stop,Pause
[main ] INFO - 01:01:30.945 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:20:00.000
[main ] INFO - 01:01:31.148 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:02:05.000
[main ] INFO - 01:01:31.710 - b : Inter@ctive TV (00:11:e1:1a:fe:f5): CurrentTrackDuration: 01:02:05.000

Please fix this!