by Bananahead » Tue Jan 14, 2014 3:23 pm
Finally an update.
I downloaded build 1686 and still had the same problem with skipping tracks. It started very well and played for about 40 minutes (if I understand the log). But then it started skipping - playing the first second or so of each track until it got to a track that it played. This is a section of the log just before the next good track. I did try playing some of the tracks that were skipped and they are all ok - play normally on desktop and with the Synology app.
Code: Select all
00185011 2613.04565430 [1536] PrepareDockedVideo: Audio
00185012 2613.05444336 [1536] Player: Updating playlist entry.
00185013 2613.06347656 [1536] UPnP: OnGetPositionInfoResponse: http://192.168.1.17:63388/ItemID=9396.flac
00185014 2613.07177734 [1536] PrepareDockedVideo: End
00185015 2613.08081055 [1536] Player: Setting current track: Z:\FLAC\The Doors\L.A. Woman [Bonus Tracks]\10. Riders on the Storm.flac
00185016 2613.08764648 [1536] UPnP: Control Request -> Action: PositionInfoResponse
00185017 2613.09570313 [1536] Player: Track entry prepared, going to start playback.
00185018 2613.10620117 [1536] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00185019 2613.11621094 [1536] Player: Song is not cached, try to play the original file
00185020 2613.12744141 [1536] TRemotePlayer.PlayURI(Z:\FLAC\The Doors\L.A. Woman [Bonus Tracks]\10. Riders on the Storm.flac, 6477)
00185021 2613.12817383 [1536] UPnP: Get IP Addresses
00185022 2613.12841797 [1536] GetNetworkAdapters started
00185023 2613.13452148 [1536] ReadExternalStream: Read = 65536, Offset = 851968, Size = 19814199, Portion = 0.04, File: Z:\FLAC\Elvis Costello\My Aim Is True\05. Alison.flac
00185024 2613.17163086 [1536] GetNetworkAdapters finished: Count = 1
00185025 2613.17285156 [1536] 2308 DB open SQL: SELECT NULL FROM Covers WHERE Covers.IDSong = ?
00185026 2613.17333984 [1536] UPnP: OnGetTransportInfoResponse: PLAYING
00185027 2613.17333984 [1536] 2308 DB lock finished, took 573.
00185028 2613.17358398 [1536] UPnP: Control Request -> Action: TransportInfoResponse
00185029 2613.17358398 [1536] TRemotePlayer.PlayURI: Generated our URI:http://192.168.1.17:63388/ItemID=6477.flac
00185030 2613.17358398 [1536] UPnP: Control Request: SetTransportStatus: PLAYING
00185031 2613.17358398 [1536] TRemotePlayer.InvokeAction(GetPositionInfo, , )
00185032 2613.17382813 [1536] GetProtocolInfoFromExt URI: http://192.168.1.17:63388/ItemID=6477.flac
00185033 2613.17407227 [1536] UPnP: GetProtocolInfo
00185034 2613.17431641 [1536] PLT_MimeType::GetMimeTypeFromExtension: No context !
00185035 2613.17529297 [1536] PLT_MimeType::GetMimeTypeFromExtension: No context !
00185036 2613.17553711 [1536] GetSimpleDIDLForSong DIDL: <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/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/"><item id="1" parentID="0" restricted="1"><dc:title>Riders on the Storm</dc:title><dc:date>2007</dc:date><upnp:artist role="Performer">The Doors</upnp:artist><upnp:artist role="AlbumArtist">The Doors</upnp:artist><upnp:album>L.A. Woman [Bonus Tracks]</upnp:album><upnp:genre>Rock</upnp:genre><upnp:albumArtURI dlna:profileID="JPEG_TN">http://192.168.1.17:63388/437.jpg</upnp:albumArtURI><upnp:originalTrackNumber>10</upnp:originalTrackNumber><res duration="0:07:08.000" size="44863678" bitrate="837321" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/flac:DLNA.ORG_PN=FLAC;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000">http://192.168.1.17:63388/ItemID=6477.flac</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
00185037 2613.17553711 [1536] TRemotePlayer.InvokeAction(Stop, , )
00185038 2613.22827148 [1536] TRemotePlayer.PlayURI: Waiting for Stop
00185039 2613.27929688 [1536] TRemotePlayer.PlayURI: Waiting for Stop
00185040 2613.27929688 [1536] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00185041 2613.29077148 [1536] UPnP: OnGetPositionInfoResponse: http://192.168.1.17:63388/ItemID=9396.flac
00185042 2613.29101563 [1536] UPnP: Control Request -> Action: PositionInfoResponse
00185043 2613.33227539 [1536] TRemotePlayer.PlayURI: Waiting for Stop
00185044 2613.41308594 [1536] UPnP: OnGetTransportInfoResponse: STOPPED
00185045 2613.47070313 [1536] UPnP: Control Request -> Action: TransportInfoResponse
00185046 2613.47802734 [1536] TRemotePlayer.PlayURI: Waiting for Stop
00185047 2613.48730469 [1536] UPnP: Control Request: SetTransportStatus: STOPPED
00185048 2613.49609375 [1536] TRemotePlayer.SetTransportStatus: Transition from PLAYING -> STOPPED, current track: Riders on the Storm, ID: 6477, URI on client:
00185049 2613.50488281 [1536] Player: Next command started
00185050 2613.51391602 [1536] Player: Setting current track: Z:\FLAC\Dave Edmunds\Chronicles (1968-84)\08. I Knew the Bride.flac
00185051 2613.52050781 [1536] Player: Next track prepared, going to start playback
00185052 2613.52929688 [1536] PrepareDockedVideo: Begin
00185053 2613.53808594 [1536] PrepareDockedVideo: Switching=true IsStartingPlayback=true WasManuallyChanged=false
Finally an update.
I downloaded build 1686 and still had the same problem with skipping tracks. It started very well and played for about 40 minutes (if I understand the log). But then it started skipping - playing the first second or so of each track until it got to a track that it played. This is a section of the log just before the next good track. I did try playing some of the tracks that were skipped and they are all ok - play normally on desktop and with the Synology app.
[code]00185011 2613.04565430 [1536] PrepareDockedVideo: Audio
00185012 2613.05444336 [1536] Player: Updating playlist entry.
00185013 2613.06347656 [1536] UPnP: OnGetPositionInfoResponse: http://192.168.1.17:63388/ItemID=9396.flac
00185014 2613.07177734 [1536] PrepareDockedVideo: End
00185015 2613.08081055 [1536] Player: Setting current track: Z:\FLAC\The Doors\L.A. Woman [Bonus Tracks]\10. Riders on the Storm.flac
00185016 2613.08764648 [1536] UPnP: Control Request -> Action: PositionInfoResponse
00185017 2613.09570313 [1536] Player: Track entry prepared, going to start playback.
00185018 2613.10620117 [1536] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00185019 2613.11621094 [1536] Player: Song is not cached, try to play the original file
00185020 2613.12744141 [1536] TRemotePlayer.PlayURI(Z:\FLAC\The Doors\L.A. Woman [Bonus Tracks]\10. Riders on the Storm.flac, 6477)
00185021 2613.12817383 [1536] UPnP: Get IP Addresses
00185022 2613.12841797 [1536] GetNetworkAdapters started
00185023 2613.13452148 [1536] ReadExternalStream: Read = 65536, Offset = 851968, Size = 19814199, Portion = 0.04, File: Z:\FLAC\Elvis Costello\My Aim Is True\05. Alison.flac
00185024 2613.17163086 [1536] GetNetworkAdapters finished: Count = 1
00185025 2613.17285156 [1536] 2308 DB open SQL: SELECT NULL FROM Covers WHERE Covers.IDSong = ?
00185026 2613.17333984 [1536] UPnP: OnGetTransportInfoResponse: PLAYING
00185027 2613.17333984 [1536] 2308 DB lock finished, took 573.
00185028 2613.17358398 [1536] UPnP: Control Request -> Action: TransportInfoResponse
00185029 2613.17358398 [1536] TRemotePlayer.PlayURI: Generated our URI:http://192.168.1.17:63388/ItemID=6477.flac
00185030 2613.17358398 [1536] UPnP: Control Request: SetTransportStatus: PLAYING
00185031 2613.17358398 [1536] TRemotePlayer.InvokeAction(GetPositionInfo, , )
00185032 2613.17382813 [1536] GetProtocolInfoFromExt URI: http://192.168.1.17:63388/ItemID=6477.flac
00185033 2613.17407227 [1536] UPnP: GetProtocolInfo
00185034 2613.17431641 [1536] PLT_MimeType::GetMimeTypeFromExtension: No context !
00185035 2613.17529297 [1536] PLT_MimeType::GetMimeTypeFromExtension: No context !
00185036 2613.17553711 [1536] GetSimpleDIDLForSong DIDL: <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/" xmlns:dlna="urn:schemas-dlna-org:metadata-1-0/"><item id="1" parentID="0" restricted="1"><dc:title>Riders on the Storm</dc:title><dc:date>2007</dc:date><upnp:artist role="Performer">The Doors</upnp:artist><upnp:artist role="AlbumArtist">The Doors</upnp:artist><upnp:album>L.A. Woman [Bonus Tracks]</upnp:album><upnp:genre>Rock</upnp:genre><upnp:albumArtURI dlna:profileID="JPEG_TN">http://192.168.1.17:63388/437.jpg</upnp:albumArtURI><upnp:originalTrackNumber>10</upnp:originalTrackNumber><res duration="0:07:08.000" size="44863678" bitrate="837321" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/flac:DLNA.ORG_PN=FLAC;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000">http://192.168.1.17:63388/ItemID=6477.flac</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
00185037 2613.17553711 [1536] TRemotePlayer.InvokeAction(Stop, , )
00185038 2613.22827148 [1536] TRemotePlayer.PlayURI: Waiting for Stop
00185039 2613.27929688 [1536] TRemotePlayer.PlayURI: Waiting for Stop
00185040 2613.27929688 [1536] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00185041 2613.29077148 [1536] UPnP: OnGetPositionInfoResponse: http://192.168.1.17:63388/ItemID=9396.flac
00185042 2613.29101563 [1536] UPnP: Control Request -> Action: PositionInfoResponse
00185043 2613.33227539 [1536] TRemotePlayer.PlayURI: Waiting for Stop
00185044 2613.41308594 [1536] UPnP: OnGetTransportInfoResponse: STOPPED
00185045 2613.47070313 [1536] UPnP: Control Request -> Action: TransportInfoResponse
00185046 2613.47802734 [1536] TRemotePlayer.PlayURI: Waiting for Stop
00185047 2613.48730469 [1536] UPnP: Control Request: SetTransportStatus: STOPPED
00185048 2613.49609375 [1536] TRemotePlayer.SetTransportStatus: Transition from PLAYING -> STOPPED, current track: Riders on the Storm, ID: 6477, URI on client:
00185049 2613.50488281 [1536] Player: Next command started
00185050 2613.51391602 [1536] Player: Setting current track: Z:\FLAC\Dave Edmunds\Chronicles (1968-84)\08. I Knew the Bride.flac
00185051 2613.52050781 [1536] Player: Next track prepared, going to start playback
00185052 2613.52929688 [1536] PrepareDockedVideo: Begin
00185053 2613.53808594 [1536] PrepareDockedVideo: Switching=true IsStartingPlayback=true WasManuallyChanged=false [/code]