by Hobbit » Tue Jan 26, 2016 9:06 am
I've just drawn a new ticket: #JDB-801-66059
Here's the log file I attached to my bug report:
Code: Select all
00000001 0.00000000 [5472] shell\twinui\nowplayingsessionmanager\localprovider\baseprovider\lib\baseprovider.cpp(516)\NPSMDesktopProvider.dll!00007FFBEB3FE7E8: (caller: 00007FFBEB3F4A09) ReturnHr[PreRelease](274) tid(149c) 80070490 Element nicht gefunden.
00000002 0.20505723 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000003 0.40655458 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000004 0.40672857 [5336] UPnP: Control Request -> Action: TransportInfoResponse
00000005 1.19407856 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000006 1.40984571 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000007 1.41007924 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000008 2.20643210 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000009 2.42691398 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000010 2.42722869 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000011 3.19202256 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000012 3.40771246 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000013 3.40794349 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000014 4.19477558 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000015 4.40676069 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000016 4.40697956 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000017 5.19553471 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000018 5.40670776 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000019 5.40693569 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000020 6.19720936 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000021 6.40655136 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000022 6.40670395 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000023 6.58954096 [5336] Forms: Accepted WM_ACTIVATEAPP +
00000024 6.58974600 [5336] Forms: RestoreTopMost: Starting
00000025 6.59232855 [5336] MainForm: NC Activate +
00000026 6.61513281 [5472] shell\twinui\nowplayingsessionmanager\localprovider\baseprovider\lib\baseprovider.cpp(516)\NPSMDesktopProvider.dll!00007FFBEB3FE7E8: (caller: 00007FFBEB3F4A09) ReturnHr[PreRelease](275) tid(149c) 80070490 Element nicht gefunden.
00000027 6.74310923 [5336] TGenThread.Create: ThreadName: PlaybackStarter_Var , Running Threads: Internal: 0, All: 31
00000028 6.74804640 [5336] PrepareDockedVideo: Begin
00000029 6.74815464 [5336] PrepareDockedVideo: Reduced window or audio
00000030 6.74831533 [5336] PrepareDockedVideo: End
00000031 6.74894428 [5336] Player: _Play() procedure entering.
00000032 6.75314951 [5336] Player: W7 icon updated.
00000033 6.75325394 [5336] Player: Invalid track position, resetting to 0.
00000034 6.75345087 [5336] PrepareDockedVideo: Begin
00000035 6.75353909 [5336] PrepareDockedVideo: Reduced window or audio
00000036 6.75367498 [5336] PrepareDockedVideo: End
00000037 6.75376415 [5336] BQ: Starting a new task.
00000038 6.75386000 [5336] BQ: Starting a new thread.
00000039 6.77115202 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 1, All: 32
00000040 6.77555323 [5336] 3472 DB prepare SQL: SELECT Lyrics, Comment FROM Songs WHERE Songs.ID=?
00000041 6.77573586 [5336] 3472 DB open SQL: SELECT Lyrics, Comment FROM Songs WHERE Songs.ID=?
00000042 6.77643681 [5336] Thread 'BackgroundQueue' has started 1464
00000043 6.77671766 [5336] Added Cover Lists: 1
00000044 6.77681828 [5336] GetFileInfo: readPictures=spAlways
00000045 6.77710056 [5336] MP3.ReadHeader C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Walpurgisnacht.mp3
00000046 6.77723217 [5336] MP3.ReadHeader - Found ID3v2, length: 45821
00000047 6.77741385 [5336] MP3.ReadHeader CheckMoreHeaders started
00000048 6.77749968 [5336] MP3.ReadHeader CheckMoreHeaders finished
00000049 6.77759552 [5336] MP3: Going to read tag from C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Walpurgisnacht.mp3
00000050 6.78089046 [5336] Added Covers: 1
00000051 6.78103924 [5336] 1464 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00000052 6.78127146 [5336] 1464 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00000053 6.78163815 [5336] GetFileInfo: Found in DB
00000054 6.78172779 [5336] 1464 DB open SQL: SELECT * FROM Covers WHERE IDSong=24338 ORDER BY CoverOrder
00000055 6.78200102 [5336] 1464 DB lock finished, took 457.
00000056 6.78209209 [5336] Remaining Covers: 1
00000057 6.78218031 [5336] Added Covers: 3
00000058 6.96661139 [5336] BQ: Added new task. Currently 1 tasks in queue.
00000059 6.96671915 [5336] BQ: Currently running 1 threads of 8
00000060 6.98491001 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 2, All: 35
00000061 6.99036455 [5336] Thread 'BackgroundQueue' has started 8204
00000062 7.11401701 [5336] StretchStart
00000063 7.11477280 [5336] BQ: Starting a new task.
00000064 7.11485004 [5336] BQ: Added new task. Currently 2 tasks in queue.
00000065 7.11492538 [5336] BQ: Currently running 2 threads of 8
00000066 7.13372946 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 3, All: 36
00000067 7.13783550 [5336] BQ: Added new task. Currently 2 tasks in queue.
00000068 7.13791895 [5336] BQ: There are threads waiting, resume one.
00000069 7.13800144 [5336] BQ: Starting a new task.
00000070 7.13807917 [5336] BQ: Added new task. Currently 1 tasks in queue.
00000071 7.13815737 [5336] BQ: There are threads waiting, resume one.
00000072 7.13823986 [5336] BQ: Starting a new task.
00000073 7.13832569 [5336] BQ: Added new task. Currently 1 tasks in queue.
00000074 7.13844824 [5336] BQ: There are threads waiting, resume one.
00000075 7.13886023 [5336] Thread 'BackgroundQueue' has started 10132
00000076 7.19501686 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000077 7.41226149 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000078 7.41236448 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000079 8.25944424 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000080 8.48823833 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000081 8.48834133 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000082 8.69930077 [5336] Winamp simulation window received messsage 12
00000083 8.69940853 [5336] BQ: Starting a new task.
00000084 8.70043659 [5336] Player: Updating playlist entry.
00000085 8.70052814 [5336] Player: Track entry prepared, going to start playback.
00000086 8.70078468 [5336] UPnP: Get IP Addresses
00000087 8.70093441 [5336] GetNetworkAdapters started
00000088 8.73396492 [5336] GetNetworkAdapters finished: Count = 1
00000089 8.73449230 [5336] 11000 DB query prepare finished, took 92.
00000090 8.73468876 [5336] 11000 DB lock finished, took 214.
00000091 8.73484516 [5336] GetProtocolInfoFromExt URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000092 8.73491669 [5336] PLT_MimeType::GetMimeTypeFromExtension: No context !
00000093 8.73499966 [5336] 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>Walpurgisnacht</dc:title><dc:date>2002-10-07</dc:date><upnp:artist role="Performer">Schandmaul</upnp:artist><upnp:artist role="AlbumArtist">Schandmaul</upnp:artist><upnp:album>Narrenkönig</upnp:album><upnp:genre></upnp:genre><upnp:albumArtURI dlna:profileID="JPEG_TN">http://192.168.178.11:55345/3076.jpg</upnp:albumArtURI><upnp:originalTrackNumber>1</upnp:originalTrackNumber><res duration="0:04:07.558" size="4997120" bitrate="160000" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000">http://192.168.178.11:55345/ItemID=24338.mp3</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
00000094 8.73520088 [5336] TRemotePlayer.InvokeAction(SetAVTransportURI, http://192.168.178.11:55345/ItemID=24338.mp3, )
00000095 8.77909565 [5336] UPNP Server: File Request: /ItemID=24338.mp3 , Range: < -1, -1>
00000096 8.77918148 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00000097 8.77926159 [5336] PostponeComputerSleep: Enabled = 1
00000098 8.77937889 [5336] OpenQuery - Preparing query SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00000099 8.78018284 [5336] 6672 DB prepare SQL: SELECT * FROM Songs WHERE Songs.ID=?
00000100 8.78040218 [5336] 6672 DB open SQL: SELECT * FROM Songs WHERE Songs.ID=?
00000101 8.78078270 [5336] _BrowseRequest finished
00000102 8.78092575 [5336] UPnP Server: Creating HTTP entity
00000103 8.78594971 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000104 8.79873848 [5336] UPnP: OnSetAVTransportURIResult
00000105 8.79897118 [5336] TRemotePlayer.InvokeAction(Play, , )
00000106 8.83710384 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000107 8.88749123 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000108 8.93777657 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000109 8.95252705 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000110 8.95267105 [5336] UPnP: Control Request -> Action: TransportInfoResponse
00000111 8.98839951 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000112 9.19257164 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000113 9.23228264 [5336] UPnP: OnGetTransportInfoResponse: TRANSITIONING
00000114 9.23250198 [5336] UPnP: Control Request: SetTransportStatus: TRANSITIONING
00000115 9.24307156 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000116 9.24327469 [5336] Player: Playback started
00000117 9.24404621 [5336] PrepareDockedVideo: Begin
00000118 9.24428844 [5336] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false
00000119 9.24458694 [5336] PrepareDockedVideo: Audio
00000120 9.24518394 [5336] Thread 'PlaybackStarter_Var'has finished 11000
00000121 9.31667900 [5336] ReadExternalStream: Read = 65536, Offset = 196608, Size = 4997120, Portion = 0.04, File: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Walpurgisnacht.mp3
[...]
00001255 256.41601563 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001256 256.64187622 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24338.mp3
00001257 257.20791626 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001258 257.41555786 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00001259 257.41564941 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00001260 257.41577148 [5336] Player: Next command started
00001261 257.41589355 [5336] Player: Next track prepared, going to start playback
00001262 257.41613770 [5336] PrepareDockedVideo: Switching=true IsStartingPlayback=false WasManuallyChanged=false
00001263 257.41622925 [5336] BQ: Starting a new task.
00001264 257.41632080 [5336] BQ: Starting a new thread.
00001265 257.43368530 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 0, All: 29
00001266 257.43832397 [5336] 3472 DB open SQL: SELECT Lyrics, Comment FROM Songs WHERE Songs.ID=?
00001267 257.43923950 [5336] Thread 'BackgroundQueue' has started 5444
00001268 257.70458984 [5336] StretchStart
00001269 257.70480347 [5336] BQ: Starting a new task.
00001270 257.70489502 [5336] BQ: Starting a new thread.
00001271 257.72244263 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 1, All: 30
00001272 257.72769165 [5336] Thread 'BackgroundQueue' has started 7840
00001273 257.72860718 [5336] StretchEnd
00001274 257.73446655 [5336] BQ: Added new task. Currently 1 tasks in queue.
00001275 257.73455811 [5336] BQ: There are threads waiting, resume one.
00001276 257.94674683 [5336] StretchStart
00001277 257.94787598 [5336] BQ: Added new task. Currently 1 tasks in queue.
00001278 257.94796753 [5336] BQ: There are threads waiting, resume one.
00001279 257.94805908 [5336] BQ: Starting a new task.
00001280 257.94812012 [5336] BQ: Starting a new thread.
00001281 257.96768188 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 2, All: 31
00001282 257.97232056 [5336] BQ: Added new task. Currently 2 tasks in queue.
00001283 257.97244263 [5336] BQ: There are threads waiting, resume one.
00001284 257.97250366 [5336] BQ: Starting a new task.
00001285 257.97259521 [5336] BQ: Added new task. Currently 1 tasks in queue.
00001286 257.97265625 [5336] BQ: There are threads waiting, resume one.
00001287 257.97274780 [5336] BQ: Starting a new task.
00001288 257.97283936 [5336] BQ: Added new task. Currently 1 tasks in queue.
00001289 257.97293091 [5336] BQ: There are threads waiting, resume one.
00001290 257.97338867 [5336] Thread 'BackgroundQueue' has started 9740
00001291 258.27313232 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001292 258.53793335 [5336] Winamp simulation window received messsage 12
00001293 258.53808594 [5336] BQ: Starting a new task.
00001294 258.54373169 [5336] Winamp simulation window received messsage 14
00001295 258.54394531 [5336] PrepareDockedVideo: Begin
00001296 258.54400635 [5336] PrepareDockedVideo: Reduced window or audio
00001297 258.54415894 [5336] PrepareDockedVideo: End
00001298 258.56484985 [5336] TGenThread.Create: ThreadName: PlaybackStarter_Var , Running Threads: Internal: 3, All: 33
00001299 258.57037354 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00001300 258.57049561 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00001301 258.57147217 [5336] Player: _Play() procedure entering.
00001302 258.57546997 [5336] Player: W7 icon updated.
00001303 258.57556152 [5336] Player: Updating playlist entry.
00001304 258.57562256 [5336] Player: Track entry prepared, going to start playback.
00001305 258.57583618 [5336] TRemotePlayer.PlayURI(C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3, 23294)
00001306 258.57604980 [5336] GetNetworkAdapters started
00001307 258.59869385 [5336] GetNetworkAdapters finished: Count = 1
00001308 258.59921265 [5336] 7552 DB query prepare finished, took 96.
00001309 258.59942627 [5336] 7552 DB lock finished, took 234.
00001310 258.59957886 [5336] GetProtocolInfoFromExt URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001311 258.59963989 [5336] PLT_MimeType::GetMimeTypeFromExtension: No context !
00001312 258.59973145 [5336] 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>Palästinalied</dc:title><dc:date>1998</dc:date><upnp:artist role="Performer">In Extremo</upnp:artist><upnp:artist role="AlbumArtist">In Extremo</upnp:artist><upnp:album>Weckt Die Toten</upnp:album><upnp:genre></upnp:genre><upnp:albumArtURI dlna:profileID="JPEG_TN">http://192.168.178.11:55345/2944.jpg</upnp:albumArtURI><upnp:originalTrackNumber>7</upnp:originalTrackNumber><res duration="0:05:22.499" size="5251072" bitrate="128000" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000">http://192.168.178.11:55345/ItemID=23294.mp3</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
00001313 258.59991455 [5336] TRemotePlayer.InvokeAction(SetAVTransportURI, http://192.168.178.11:55345/ItemID=23294.mp3, )
00001314 258.64123535 [5336] UPNP Server: File Request: /ItemID=23294.mp3 , Range: < -1, -1>
00001315 258.64132690 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00001316 258.64141846 [5336] 5372 DB open SQL: SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00001317 258.64190674 [5336] 5372 DB lock finished, took 856.
00001318 258.64221191 [5336] 5372 DB query prepare finished, took 273.
00001319 258.64239502 [5336] 5372 DB lock finished, took 214.
00001320 258.64266968 [5336] UPnP Server: Serving file C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3, ExternalStream = 1, FileLen = 5251072
00001321 258.65029907 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001322 258.65982056 [5336] UPnP: OnSetAVTransportURIResult
00001323 258.65994263 [5336] TRemotePlayer.InvokeAction(Play, , )
00001324 258.70083618 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001325 258.75134277 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001326 258.80187988 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001327 258.82775879 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00001328 258.82785034 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00001329 258.85244751 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001330 258.90295410 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001331 258.91323853 [5336] UPNP Server: File Request: /ItemID=23294.mp3 , Range: < -1, -1>
00001332 258.91336060 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00001333 258.91345215 [5336] 11200 DB open SQL: SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00001334 258.91397095 [5336] 11200 DB lock finished, took 916.
00001335 258.91427612 [5336] 11200 DB query prepare finished, took 274.
00001336 258.91445923 [5336] 11200 DB lock finished, took 220.
00001337 258.91473389 [5336] UPnP Server: Serving file C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3, ExternalStream = 1, FileLen = 5251072
00001338 258.94909668 [5336] UPNP Server: File Request: /ItemID=23294.mp3 , Range: < 0, -1>
00001339 258.94924927 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00001340 258.94934082 [5336] 1076 DB open SQL: SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00001341 258.94982910 [5336] 1076 DB lock finished, took 859.
00001342 258.95013428 [5336] 1076 DB query prepare finished, took 268.
00001343 258.95034790 [5336] 1076 DB lock finished, took 209.
00001344 258.95059204 [5336] UPnP Server: Serving file C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3, ExternalStream = 1, FileLen = 5251072
00001345 258.95101929 [5336] ReadExternalStream: Read = 65536, Offset = 0, Size = 5251072, Portion = 0.00, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001346 258.95349121 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001347 258.95669556 [5336] ReadExternalStream: Read = 65536, Offset = 131072, Size = 5251072, Portion = 0.02, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001348 259.12393188 [5336] UPnP: OnGetTransportInfoResponse: TRANSITIONING
00001349 259.12405396 [5336] UPnP: Control Request: SetTransportStatus: TRANSITIONING
00001350 259.12808228 [5336] ReadExternalStream: Read = 65536, Offset = 196608, Size = 5251072, Portion = 0.04, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001351 259.15560913 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001352 259.15573120 [5336] Player: Playback started
00001353 259.15612793 [5336] PrepareDockedVideo: Begin
00001354 259.15625000 [5336] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false
00001355 259.15640259 [5336] PrepareDockedVideo: Audio
00001356 259.15682983 [5336] Thread 'PlaybackStarter_Var'has finished 7552
00001357 259.17364502 [5336] UpdateSongPictures: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001358 259.17373657 [5336] GetFileInfo: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001359 259.17379761 [5336] GetFileInfo: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001360 259.17401123 [5336] MP3.ReadHeader - File size 5251072
00001361 259.17413330 [5336] MP3.ReadHeader framestarts: 90948, 90948
00001362 259.17422485 [5336] MP3.ReadHeader CheckMoreHeaders Endtest: 94020
00001363 259.17431641 [5336] MP3.ReadHeader finished
00001364 259.17630005 [5336] Added Covers: 7
00001365 259.17642212 [5336] 3472 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00001366 259.17669678 [5336] 3472 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00001367 259.17703247 [5336] GetFileInfo: Found in DB
00001368 259.17712402 [5336] 3472 DB open SQL: SELECT * FROM Covers WHERE IDSong=23294 ORDER BY CoverOrder
00001369 259.17733765 [5336] 3472 DB lock finished, took 362.
00001370 259.17745972 [5336] Remaining Covers: 7
00001371 259.17752075 [5336] Added Covers: 9
00001372 259.24279785 [5336] StretchStart
00001373 259.24340820 [5336] ReadExternalStream: Read = 65536, Offset = 262144, Size = 5251072, Portion = 0.05, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001374 259.24356079 [5336] BQ: Starting a new task.
00001375 259.24929810 [5336] StretchEnd
00001376 259.24951172 [5336] Remaining Covers: 7
00001377 259.25122070 [5336] Remaining Covers: 6
00001378 259.37594604 [5336] ReadExternalStream: Read = 65536, Offset = 327680, Size = 5251072, Portion = 0.06, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001379 259.39120483 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001380 259.39132690 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001381 259.43936157 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001382 259.44992065 [5336] UPnP: Control Request: Waiting...
00001383 259.62054443 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001384 260.20809937 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001385 260.41720581 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001386 260.41732788 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001387 260.64093018 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001388 261.09954834 [5336] Going 'BackgroundQueue'to make final callback 7840
00001389 261.20971680 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001390 261.41598511 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001391 261.41607666 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001392 261.53833008 [5336] Going 'BackgroundQueue'to make final callback 5444
00001393 261.53851318 [5336] Thread 'BackgroundQueue'has finished 5444
00001394 261.64086914 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001395 262.07296753 [5336] ReadExternalStream: Read = 65536, Offset = 393216, Size = 5251072, Portion = 0.07, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001396 262.21041870 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001397 262.24932861 [5336] Going 'BackgroundQueue'to make final callback 9740
00001398 262.41735840 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001399 262.41748047 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001400 262.64465332 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001401 263.20840454 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001402 263.41738892 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001403 263.41744995 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
[...]
00004688 1026.02697754 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00004689 1026.02709961 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00004690 1026.02722168 [5336] Player: Next command started
00004691 1026.02734375 [5336] Player: Next track prepared, going to start playback
00004692 1026.02758789 [5336] PrepareDockedVideo: Switching=true IsStartingPlayback=false WasManuallyChanged=false
00004693 1026.02770996 [5336] Remaining Covers: 4
00004694 1026.02783203 [5336] BQ: Added new task. Currently 1 tasks in queue.
00004695 1026.02783203 [5336] BQ: Currently running 0 threads of 8
00004696 1026.04272461 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 0, All: 28
00004697 1026.04785156 [5336] 3472 DB open SQL: SELECT Lyrics, Comment FROM Songs WHERE Songs.ID=?
00004698 1026.04870605 [5336] Thread 'BackgroundQueue' has started 7644
00004699 1026.04895020 [5336] Added Cover Lists: 2
00004700 1026.04907227 [5336] GetFileInfo: readPictures=spAlways
00004701 1026.04919434 [5336] MP3.ReadHeader C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004702 1026.04931641 [5336] MP3.ReadHeader - Found ID3v2, length: 46427
00004703 1026.04943848 [5336] MP3.ReadHeader CheckMoreHeaders started
00004704 1026.04943848 [5336] MP3.ReadHeader CheckMoreHeaders finished
00004705 1026.04956055 [5336] MP3: Going to read tag from C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004706 1026.05187988 [5336] MP3: Tag read from C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004707 1026.05212402 [5336] 7644 DB query prepare finished, took 365.
00004708 1026.05236816 [5336] 7644 DB lock finished, took 237.
00004709 1026.05249023 [5336] UpdateSongPictures: CoverList.LoadForSongId, C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004710 1026.05261230 [5336] OpenQuery - Preparing query SELECT * FROM Covers WHERE IDSong=24336 ORDER BY CoverOrder
00004711 1026.05285645 [5336] Added Covers: 6
00004712 1026.05297852 [5336] Added Covers: 6
00004713 1026.18725586 [5336] StretchStart
00004714 1026.18750000 [5336] BQ: Starting a new task.
00004715 1026.18762207 [5336] BQ: Starting a new thread.
00004716 1026.20263672 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 1, All: 29
00004717 1026.20837402 [5336] Thread 'BackgroundQueue' has started 8536
00004718 1026.20922852 [5336] StretchEnd
00004719 1026.21276855 [5336] Remaining Covers: 6
00004720 1026.21289063 [5336] BQ: Starting a new task.
00004721 1026.45544434 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 2, All: 30
00004722 1026.46032715 [5336] BQ: Added new task. Currently 2 tasks in queue.
00004723 1026.46044922 [5336] BQ: There are threads waiting, resume one.
00004724 1026.46057129 [5336] BQ: Starting a new task.
00004725 1026.46057129 [5336] BQ: Added new task. Currently 1 tasks in queue.
00004726 1026.46069336 [5336] BQ: There are threads waiting, resume one.
00004727 1026.46069336 [5336] BQ: Starting a new task.
00004728 1026.46081543 [5336] BQ: Added new task. Currently 1 tasks in queue.
00004729 1026.46093750 [5336] BQ: There are threads waiting, resume one.
00004730 1026.46179199 [5336] Thread 'BackgroundQueue' has started 8048
00004731 1026.87353516 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00004732 1027.92541504 [5336] Winamp simulation window received messsage 12
00004733 1027.92553711 [5336] BQ: Starting a new task.
00004734 1027.93066406 [5336] Winamp simulation window received messsage 14
00004735 1027.93090820 [5336] PrepareDockedVideo: Begin
00004736 1027.93090820 [5336] PrepareDockedVideo: Reduced window or audio
00004737 1027.93103027 [5336] PrepareDockedVideo: End
00004738 1027.95361328 [5336] TGenThread.Create: ThreadName: PlaybackStarter_Var , Running Threads: Internal: 3, All: 33
00004739 1027.95837402 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00004740 1027.95837402 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00004741 1027.95971680 [5336] Player: _Play() procedure entering.
00004742 1027.96398926 [5336] Player: W7 icon updated.
00004743 1027.96411133 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00004744 1027.96423340 [5336] Player: Setting current track: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004745 1027.96435547 [5336] Player: Song is not cached, try to play the original file
00004746 1027.96459961 [5336] UPnP: Get IP Addresses
00004747 1027.98742676 [5336] GetNetworkAdapters finished: Count = 1
00004748 1027.98791504 [5336] 2044 DB query prepare finished, took 97.
00004749 1027.98803711 [5336] 2044 DB lock finished, took 222.
00004750 1027.98815918 [5336] GetProtocolInfoFromExt URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004751 1027.98828125 [5336] PLT_MimeType::GetMimeTypeFromExtension: No context !
00004752 1027.98840332 [5336] 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>Vogelfrei</dc:title><dc:date>2002-10-07</dc:date><upnp:artist role="Performer">Schandmaul</upnp:artist><upnp:artist role="AlbumArtist">Schandmaul</upnp:artist><upnp:album>Narrenkönig</upnp:album><upnp:genre></upnp:genre><upnp:albumArtURI dlna:profileID="JPEG_TN">http://192.168.178.11:55345/3076.jpg</upnp:albumArtURI><upnp:originalTrackNumber>9</upnp:originalTrackNumber><res duration="0:05:06.202" size="6170624" bitrate="160000" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000">http://192.168.178.11:55345/ItemID=24336.mp3</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
00004753 1027.98852539 [5336] TRemotePlayer.InvokeAction(SetAVTransportURI, http://192.168.178.11:55345/ItemID=24336.mp3, )
00004754 1028.03442383 [5336] UPNP Server: File Request: /ItemID=24336.mp3 , Range: < -1, -1>
00004755 1028.03454590 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00004756 1028.03454590 [5336] PostponeComputerSleep: Enabled = 1
00004757 1028.03466797 [5336] OpenQuery - Preparing query SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00004758 1028.03527832 [5336] 9940 DB prepare SQL: SELECT * FROM Songs WHERE Songs.ID=?
00004759 1028.03552246 [5336] 9940 DB open SQL: SELECT * FROM Songs WHERE Songs.ID=?
00004760 1028.03588867 [5336] _BrowseRequest finished
00004761 1028.03601074 [5336] UPnP Server: Creating HTTP entity
00004762 1028.03881836 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004763 1028.05358887 [5336] UPnP: OnSetAVTransportURIResult
00004764 1028.05371094 [5336] TRemotePlayer.InvokeAction(Play, , )
00004765 1028.08947754 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004766 1028.14001465 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004767 1028.16796875 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00004768 1028.16796875 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00004769 1028.19055176 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004770 1028.24108887 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004771 1028.29162598 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004772 1028.30627441 [5336] UPNP Server: File Request: /ItemID=24336.mp3 , Range: < -1, -1>
00004773 1028.30627441 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00004774 1028.30639648 [5336] 4556 DB open SQL: SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00004775 1028.30688477 [5336] 4556 DB lock finished, took 902.
00004776 1028.30725098 [5336] 4556 DB query prepare finished, took 298.
00004777 1028.30737305 [5336] 4556 DB lock finished, took 212.
00004778 1028.30761719 [5336] UPnP Server: Serving file C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3, ExternalStream = 1, FileLen = 6170624
00004779 1028.54431152 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004780 1028.54443359 [5336] Player: Playback started
00004781 1028.54479980 [5336] DB: Deleting prepared query: SELECT NULL FROM Covers WHERE Covers.IDSong = ?
00004782 1028.54492188 [5336] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false
00004783 1028.54504395 [5336] PrepareDockedVideo: Audio
00004784 1028.54577637 [5336] ReadExternalStream: Read = 65536, Offset = 196608, Size = 6170624, Portion = 0.03, File: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004785 1028.55151367 [5336] UpdateSongPictures: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004786 1028.55163574 [5336] GetFileInfo: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004787 1028.55163574 [5336] GetFileInfo: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004788 1028.55187988 [5336] MP3.ReadHeader - File size 6170624
00004789 1028.55200195 [5336] MP3.ReadHeader framestarts: 46437, 46437
00004790 1028.55212402 [5336] MP3.ReadHeader CheckMoreHeaders Endtest: 49509
00004791 1028.55224609 [5336] MP3.ReadHeader finished
00004792 1028.55444336 [5336] Added Covers: 7
00004793 1028.55456543 [5336] 3472 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00004794 1028.55493164 [5336] GetFileInfo: Found in DB
00004795 1028.55493164 [5336] 3472 DB open SQL: SELECT * FROM Covers WHERE IDSong=24336 ORDER BY CoverOrder
00004796 1028.55517578 [5336] 3472 DB lock finished, took 386.
00004797 1028.55529785 [5336] Remaining Covers: 7
00004798 1028.55541992 [5336] Added Covers: 9
00004799 1028.61340332 [5336] ReadExternalStream: Read = 65536, Offset = 262144, Size = 6170624, Portion = 0.04, File: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004800 1028.63049316 [5336] StretchStart
00004801 1028.63085938 [5336] BQ: Added new task. Currently 1 tasks in queue.
00004802 1028.63098145 [5336] BQ: There are threads waiting, resume one.
00004803 1028.63354492 [5336] Remaining Covers: 8
00004804 1028.63366699 [5336] Remaining Cover Lists: 2
00004805 1028.73437500 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004806 1028.73449707 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004807 1028.78430176 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004808 1028.79443359 [5336] UPnP: Control Request: Waiting...
00004809 1028.97485352 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004810 1029.02807617 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004811 1029.03869629 [5336] UPnP: Control Request: Waiting...
00004812 1029.03869629 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004813 1029.25537109 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004814 1029.52050781 [5336] Going 'BackgroundQueue'to make final callback 8536
00004815 1030.02673340 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004816 1030.02685547 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004817 1030.25329590 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004818 1030.92541504 [5336] Going 'BackgroundQueue'to make final callback 7644
00004819 1030.92553711 [5336] Thread 'BackgroundQueue'has finished 7644
00004820 1031.02807617 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004821 1031.02819824 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004822 1031.25439453 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004823 1031.63342285 [5336] Going 'BackgroundQueue'to make final callback 8048
00004824 1031.91638184 [5336] ReadExternalStream: Read = 65536, Offset = 393216, Size = 6170624, Portion = 0.06, File: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004825 1032.02673340 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004826 1032.02685547 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004827 1032.23522949 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
[...]
00005926 1334.03540039 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00005927 1334.25964355 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00005928 1334.81970215 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00005929 1335.03686523 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005930 1335.03698730 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00005931 1335.03710938 [5336] Player: Next command started
00005932 1335.03723145 [5336] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false
00005933 1335.03735352 [5336] PrepareDockedVideo: Audio
00005934 1336.03674316 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005935 1336.03674316 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
[...]
00005948 1343.03857422 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005949 1343.03881836 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00005950 1343.14660645 [2592] base\appmodel\execmodel\modern\lifetimemanager\suspendresume.cpp(4263)\modernexecserver.dll!00007FFBFDDC102A: (caller: 00007FFBFDDA78E3) ReturnHr[PreRelease](144) tid(24cc) 8000000D Eine unzulässige Statusänderung wurde angefordert.
00005951 1344.03588867 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005952 1344.03613281 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
[...]
00005959 1348.03503418 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005960 1348.03527832 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00005961 1348.22595215 [5336] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 26
00005962 1348.23156738 [5336] Thread 'UpdateDriveLetters_Var' has started 6424
00005963 1348.23498535 [5336] >UpdateDriveLetters - end<
00005964 1348.23510742 [5336] Thread 'UpdateDriveLetters_Var'has finished 6424
00005965 1349.03503418 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005966 1349.03527832 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
[...]
I condensed this log to 3 mp3 files. The original log contains 5 files. I've cut out many lines which seemed to be obsolete to the problem.
I played 5 files. File #1, #3 and #5 have "full" information about the release date of the album ("DD.MM.YYYY"). Song #2 and #4 have only the year of release ("YYYY"). For #1, #3 and #5 all file information is correctly transmitted and displayed on my renderer. #2 and #4 lead only to displaying the already mentioned network path. I found some command lines where it says "found ID3v2". This line only shows up for files #1, #3 and #5 and can be found in line 00000046, 00002860 and 00004702. Maybe this helps to find a solution?
I've just drawn a new ticket: #JDB-801-66059
Here's the log file I attached to my bug report:
[code]00000001 0.00000000 [5472] shell\twinui\nowplayingsessionmanager\localprovider\baseprovider\lib\baseprovider.cpp(516)\NPSMDesktopProvider.dll!00007FFBEB3FE7E8: (caller: 00007FFBEB3F4A09) ReturnHr[PreRelease](274) tid(149c) 80070490 Element nicht gefunden.
00000002 0.20505723 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000003 0.40655458 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000004 0.40672857 [5336] UPnP: Control Request -> Action: TransportInfoResponse
00000005 1.19407856 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000006 1.40984571 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000007 1.41007924 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000008 2.20643210 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000009 2.42691398 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000010 2.42722869 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000011 3.19202256 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000012 3.40771246 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000013 3.40794349 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000014 4.19477558 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000015 4.40676069 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000016 4.40697956 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000017 5.19553471 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000018 5.40670776 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000019 5.40693569 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000020 6.19720936 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000021 6.40655136 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000022 6.40670395 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000023 6.58954096 [5336] Forms: Accepted WM_ACTIVATEAPP +
00000024 6.58974600 [5336] Forms: RestoreTopMost: Starting
00000025 6.59232855 [5336] MainForm: NC Activate +
00000026 6.61513281 [5472] shell\twinui\nowplayingsessionmanager\localprovider\baseprovider\lib\baseprovider.cpp(516)\NPSMDesktopProvider.dll!00007FFBEB3FE7E8: (caller: 00007FFBEB3F4A09) ReturnHr[PreRelease](275) tid(149c) 80070490 Element nicht gefunden.
00000027 6.74310923 [5336] TGenThread.Create: ThreadName: PlaybackStarter_Var , Running Threads: Internal: 0, All: 31
00000028 6.74804640 [5336] PrepareDockedVideo: Begin
00000029 6.74815464 [5336] PrepareDockedVideo: Reduced window or audio
00000030 6.74831533 [5336] PrepareDockedVideo: End
00000031 6.74894428 [5336] Player: _Play() procedure entering.
00000032 6.75314951 [5336] Player: W7 icon updated.
00000033 6.75325394 [5336] Player: Invalid track position, resetting to 0.
00000034 6.75345087 [5336] PrepareDockedVideo: Begin
00000035 6.75353909 [5336] PrepareDockedVideo: Reduced window or audio
00000036 6.75367498 [5336] PrepareDockedVideo: End
00000037 6.75376415 [5336] BQ: Starting a new task.
00000038 6.75386000 [5336] BQ: Starting a new thread.
00000039 6.77115202 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 1, All: 32
00000040 6.77555323 [5336] 3472 DB prepare SQL: SELECT Lyrics, Comment FROM Songs WHERE Songs.ID=?
00000041 6.77573586 [5336] 3472 DB open SQL: SELECT Lyrics, Comment FROM Songs WHERE Songs.ID=?
00000042 6.77643681 [5336] Thread 'BackgroundQueue' has started 1464
00000043 6.77671766 [5336] Added Cover Lists: 1
00000044 6.77681828 [5336] GetFileInfo: readPictures=spAlways
00000045 6.77710056 [5336] MP3.ReadHeader C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Walpurgisnacht.mp3
00000046 6.77723217 [5336] MP3.ReadHeader - Found ID3v2, length: 45821
00000047 6.77741385 [5336] MP3.ReadHeader CheckMoreHeaders started
00000048 6.77749968 [5336] MP3.ReadHeader CheckMoreHeaders finished
00000049 6.77759552 [5336] MP3: Going to read tag from C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Walpurgisnacht.mp3
00000050 6.78089046 [5336] Added Covers: 1
00000051 6.78103924 [5336] 1464 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00000052 6.78127146 [5336] 1464 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00000053 6.78163815 [5336] GetFileInfo: Found in DB
00000054 6.78172779 [5336] 1464 DB open SQL: SELECT * FROM Covers WHERE IDSong=24338 ORDER BY CoverOrder
00000055 6.78200102 [5336] 1464 DB lock finished, took 457.
00000056 6.78209209 [5336] Remaining Covers: 1
00000057 6.78218031 [5336] Added Covers: 3
00000058 6.96661139 [5336] BQ: Added new task. Currently 1 tasks in queue.
00000059 6.96671915 [5336] BQ: Currently running 1 threads of 8
00000060 6.98491001 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 2, All: 35
00000061 6.99036455 [5336] Thread 'BackgroundQueue' has started 8204
00000062 7.11401701 [5336] StretchStart
00000063 7.11477280 [5336] BQ: Starting a new task.
00000064 7.11485004 [5336] BQ: Added new task. Currently 2 tasks in queue.
00000065 7.11492538 [5336] BQ: Currently running 2 threads of 8
00000066 7.13372946 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 3, All: 36
00000067 7.13783550 [5336] BQ: Added new task. Currently 2 tasks in queue.
00000068 7.13791895 [5336] BQ: There are threads waiting, resume one.
00000069 7.13800144 [5336] BQ: Starting a new task.
00000070 7.13807917 [5336] BQ: Added new task. Currently 1 tasks in queue.
00000071 7.13815737 [5336] BQ: There are threads waiting, resume one.
00000072 7.13823986 [5336] BQ: Starting a new task.
00000073 7.13832569 [5336] BQ: Added new task. Currently 1 tasks in queue.
00000074 7.13844824 [5336] BQ: There are threads waiting, resume one.
00000075 7.13886023 [5336] Thread 'BackgroundQueue' has started 10132
00000076 7.19501686 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000077 7.41226149 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000078 7.41236448 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000079 8.25944424 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00000080 8.48823833 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000081 8.48834133 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00000082 8.69930077 [5336] Winamp simulation window received messsage 12
00000083 8.69940853 [5336] BQ: Starting a new task.
00000084 8.70043659 [5336] Player: Updating playlist entry.
00000085 8.70052814 [5336] Player: Track entry prepared, going to start playback.
00000086 8.70078468 [5336] UPnP: Get IP Addresses
00000087 8.70093441 [5336] GetNetworkAdapters started
00000088 8.73396492 [5336] GetNetworkAdapters finished: Count = 1
00000089 8.73449230 [5336] 11000 DB query prepare finished, took 92.
00000090 8.73468876 [5336] 11000 DB lock finished, took 214.
00000091 8.73484516 [5336] GetProtocolInfoFromExt URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000092 8.73491669 [5336] PLT_MimeType::GetMimeTypeFromExtension: No context !
00000093 8.73499966 [5336] 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>Walpurgisnacht</dc:title><dc:date>2002-10-07</dc:date><upnp:artist role="Performer">Schandmaul</upnp:artist><upnp:artist role="AlbumArtist">Schandmaul</upnp:artist><upnp:album>Narrenkönig</upnp:album><upnp:genre></upnp:genre><upnp:albumArtURI dlna:profileID="JPEG_TN">http://192.168.178.11:55345/3076.jpg</upnp:albumArtURI><upnp:originalTrackNumber>1</upnp:originalTrackNumber><res duration="0:04:07.558" size="4997120" bitrate="160000" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000">http://192.168.178.11:55345/ItemID=24338.mp3</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
00000094 8.73520088 [5336] TRemotePlayer.InvokeAction(SetAVTransportURI, http://192.168.178.11:55345/ItemID=24338.mp3, )
00000095 8.77909565 [5336] UPNP Server: File Request: /ItemID=24338.mp3 , Range: < -1, -1>
00000096 8.77918148 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00000097 8.77926159 [5336] PostponeComputerSleep: Enabled = 1
00000098 8.77937889 [5336] OpenQuery - Preparing query SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00000099 8.78018284 [5336] 6672 DB prepare SQL: SELECT * FROM Songs WHERE Songs.ID=?
00000100 8.78040218 [5336] 6672 DB open SQL: SELECT * FROM Songs WHERE Songs.ID=?
00000101 8.78078270 [5336] _BrowseRequest finished
00000102 8.78092575 [5336] UPnP Server: Creating HTTP entity
00000103 8.78594971 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000104 8.79873848 [5336] UPnP: OnSetAVTransportURIResult
00000105 8.79897118 [5336] TRemotePlayer.InvokeAction(Play, , )
00000106 8.83710384 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000107 8.88749123 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000108 8.93777657 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000109 8.95252705 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00000110 8.95267105 [5336] UPnP: Control Request -> Action: TransportInfoResponse
00000111 8.98839951 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000112 9.19257164 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000113 9.23228264 [5336] UPnP: OnGetTransportInfoResponse: TRANSITIONING
00000114 9.23250198 [5336] UPnP: Control Request: SetTransportStatus: TRANSITIONING
00000115 9.24307156 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24338.mp3
00000116 9.24327469 [5336] Player: Playback started
00000117 9.24404621 [5336] PrepareDockedVideo: Begin
00000118 9.24428844 [5336] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false
00000119 9.24458694 [5336] PrepareDockedVideo: Audio
00000120 9.24518394 [5336] Thread 'PlaybackStarter_Var'has finished 11000
00000121 9.31667900 [5336] ReadExternalStream: Read = 65536, Offset = 196608, Size = 4997120, Portion = 0.04, File: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Walpurgisnacht.mp3
[...]
00001255 256.41601563 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001256 256.64187622 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24338.mp3
00001257 257.20791626 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001258 257.41555786 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00001259 257.41564941 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00001260 257.41577148 [5336] Player: Next command started
00001261 257.41589355 [5336] Player: Next track prepared, going to start playback
00001262 257.41613770 [5336] PrepareDockedVideo: Switching=true IsStartingPlayback=false WasManuallyChanged=false
00001263 257.41622925 [5336] BQ: Starting a new task.
00001264 257.41632080 [5336] BQ: Starting a new thread.
00001265 257.43368530 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 0, All: 29
00001266 257.43832397 [5336] 3472 DB open SQL: SELECT Lyrics, Comment FROM Songs WHERE Songs.ID=?
00001267 257.43923950 [5336] Thread 'BackgroundQueue' has started 5444
00001268 257.70458984 [5336] StretchStart
00001269 257.70480347 [5336] BQ: Starting a new task.
00001270 257.70489502 [5336] BQ: Starting a new thread.
00001271 257.72244263 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 1, All: 30
00001272 257.72769165 [5336] Thread 'BackgroundQueue' has started 7840
00001273 257.72860718 [5336] StretchEnd
00001274 257.73446655 [5336] BQ: Added new task. Currently 1 tasks in queue.
00001275 257.73455811 [5336] BQ: There are threads waiting, resume one.
00001276 257.94674683 [5336] StretchStart
00001277 257.94787598 [5336] BQ: Added new task. Currently 1 tasks in queue.
00001278 257.94796753 [5336] BQ: There are threads waiting, resume one.
00001279 257.94805908 [5336] BQ: Starting a new task.
00001280 257.94812012 [5336] BQ: Starting a new thread.
00001281 257.96768188 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 2, All: 31
00001282 257.97232056 [5336] BQ: Added new task. Currently 2 tasks in queue.
00001283 257.97244263 [5336] BQ: There are threads waiting, resume one.
00001284 257.97250366 [5336] BQ: Starting a new task.
00001285 257.97259521 [5336] BQ: Added new task. Currently 1 tasks in queue.
00001286 257.97265625 [5336] BQ: There are threads waiting, resume one.
00001287 257.97274780 [5336] BQ: Starting a new task.
00001288 257.97283936 [5336] BQ: Added new task. Currently 1 tasks in queue.
00001289 257.97293091 [5336] BQ: There are threads waiting, resume one.
00001290 257.97338867 [5336] Thread 'BackgroundQueue' has started 9740
00001291 258.27313232 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001292 258.53793335 [5336] Winamp simulation window received messsage 12
00001293 258.53808594 [5336] BQ: Starting a new task.
00001294 258.54373169 [5336] Winamp simulation window received messsage 14
00001295 258.54394531 [5336] PrepareDockedVideo: Begin
00001296 258.54400635 [5336] PrepareDockedVideo: Reduced window or audio
00001297 258.54415894 [5336] PrepareDockedVideo: End
00001298 258.56484985 [5336] TGenThread.Create: ThreadName: PlaybackStarter_Var , Running Threads: Internal: 3, All: 33
00001299 258.57037354 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00001300 258.57049561 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00001301 258.57147217 [5336] Player: _Play() procedure entering.
00001302 258.57546997 [5336] Player: W7 icon updated.
00001303 258.57556152 [5336] Player: Updating playlist entry.
00001304 258.57562256 [5336] Player: Track entry prepared, going to start playback.
00001305 258.57583618 [5336] TRemotePlayer.PlayURI(C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3, 23294)
00001306 258.57604980 [5336] GetNetworkAdapters started
00001307 258.59869385 [5336] GetNetworkAdapters finished: Count = 1
00001308 258.59921265 [5336] 7552 DB query prepare finished, took 96.
00001309 258.59942627 [5336] 7552 DB lock finished, took 234.
00001310 258.59957886 [5336] GetProtocolInfoFromExt URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001311 258.59963989 [5336] PLT_MimeType::GetMimeTypeFromExtension: No context !
00001312 258.59973145 [5336] 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>Palästinalied</dc:title><dc:date>1998</dc:date><upnp:artist role="Performer">In Extremo</upnp:artist><upnp:artist role="AlbumArtist">In Extremo</upnp:artist><upnp:album>Weckt Die Toten</upnp:album><upnp:genre></upnp:genre><upnp:albumArtURI dlna:profileID="JPEG_TN">http://192.168.178.11:55345/2944.jpg</upnp:albumArtURI><upnp:originalTrackNumber>7</upnp:originalTrackNumber><res duration="0:05:22.499" size="5251072" bitrate="128000" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000">http://192.168.178.11:55345/ItemID=23294.mp3</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
00001313 258.59991455 [5336] TRemotePlayer.InvokeAction(SetAVTransportURI, http://192.168.178.11:55345/ItemID=23294.mp3, )
00001314 258.64123535 [5336] UPNP Server: File Request: /ItemID=23294.mp3 , Range: < -1, -1>
00001315 258.64132690 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00001316 258.64141846 [5336] 5372 DB open SQL: SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00001317 258.64190674 [5336] 5372 DB lock finished, took 856.
00001318 258.64221191 [5336] 5372 DB query prepare finished, took 273.
00001319 258.64239502 [5336] 5372 DB lock finished, took 214.
00001320 258.64266968 [5336] UPnP Server: Serving file C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3, ExternalStream = 1, FileLen = 5251072
00001321 258.65029907 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001322 258.65982056 [5336] UPnP: OnSetAVTransportURIResult
00001323 258.65994263 [5336] TRemotePlayer.InvokeAction(Play, , )
00001324 258.70083618 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001325 258.75134277 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001326 258.80187988 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001327 258.82775879 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00001328 258.82785034 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00001329 258.85244751 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001330 258.90295410 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001331 258.91323853 [5336] UPNP Server: File Request: /ItemID=23294.mp3 , Range: < -1, -1>
00001332 258.91336060 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00001333 258.91345215 [5336] 11200 DB open SQL: SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00001334 258.91397095 [5336] 11200 DB lock finished, took 916.
00001335 258.91427612 [5336] 11200 DB query prepare finished, took 274.
00001336 258.91445923 [5336] 11200 DB lock finished, took 220.
00001337 258.91473389 [5336] UPnP Server: Serving file C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3, ExternalStream = 1, FileLen = 5251072
00001338 258.94909668 [5336] UPNP Server: File Request: /ItemID=23294.mp3 , Range: < 0, -1>
00001339 258.94924927 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00001340 258.94934082 [5336] 1076 DB open SQL: SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00001341 258.94982910 [5336] 1076 DB lock finished, took 859.
00001342 258.95013428 [5336] 1076 DB query prepare finished, took 268.
00001343 258.95034790 [5336] 1076 DB lock finished, took 209.
00001344 258.95059204 [5336] UPnP Server: Serving file C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3, ExternalStream = 1, FileLen = 5251072
00001345 258.95101929 [5336] ReadExternalStream: Read = 65536, Offset = 0, Size = 5251072, Portion = 0.00, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001346 258.95349121 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001347 258.95669556 [5336] ReadExternalStream: Read = 65536, Offset = 131072, Size = 5251072, Portion = 0.02, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001348 259.12393188 [5336] UPnP: OnGetTransportInfoResponse: TRANSITIONING
00001349 259.12405396 [5336] UPnP: Control Request: SetTransportStatus: TRANSITIONING
00001350 259.12808228 [5336] ReadExternalStream: Read = 65536, Offset = 196608, Size = 5251072, Portion = 0.04, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001351 259.15560913 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=23294.mp3
00001352 259.15573120 [5336] Player: Playback started
00001353 259.15612793 [5336] PrepareDockedVideo: Begin
00001354 259.15625000 [5336] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false
00001355 259.15640259 [5336] PrepareDockedVideo: Audio
00001356 259.15682983 [5336] Thread 'PlaybackStarter_Var'has finished 7552
00001357 259.17364502 [5336] UpdateSongPictures: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001358 259.17373657 [5336] GetFileInfo: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001359 259.17379761 [5336] GetFileInfo: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001360 259.17401123 [5336] MP3.ReadHeader - File size 5251072
00001361 259.17413330 [5336] MP3.ReadHeader framestarts: 90948, 90948
00001362 259.17422485 [5336] MP3.ReadHeader CheckMoreHeaders Endtest: 94020
00001363 259.17431641 [5336] MP3.ReadHeader finished
00001364 259.17630005 [5336] Added Covers: 7
00001365 259.17642212 [5336] 3472 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00001366 259.17669678 [5336] 3472 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00001367 259.17703247 [5336] GetFileInfo: Found in DB
00001368 259.17712402 [5336] 3472 DB open SQL: SELECT * FROM Covers WHERE IDSong=23294 ORDER BY CoverOrder
00001369 259.17733765 [5336] 3472 DB lock finished, took 362.
00001370 259.17745972 [5336] Remaining Covers: 7
00001371 259.17752075 [5336] Added Covers: 9
00001372 259.24279785 [5336] StretchStart
00001373 259.24340820 [5336] ReadExternalStream: Read = 65536, Offset = 262144, Size = 5251072, Portion = 0.05, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001374 259.24356079 [5336] BQ: Starting a new task.
00001375 259.24929810 [5336] StretchEnd
00001376 259.24951172 [5336] Remaining Covers: 7
00001377 259.25122070 [5336] Remaining Covers: 6
00001378 259.37594604 [5336] ReadExternalStream: Read = 65536, Offset = 327680, Size = 5251072, Portion = 0.06, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001379 259.39120483 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001380 259.39132690 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001381 259.43936157 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001382 259.44992065 [5336] UPnP: Control Request: Waiting...
00001383 259.62054443 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001384 260.20809937 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001385 260.41720581 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001386 260.41732788 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001387 260.64093018 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001388 261.09954834 [5336] Going 'BackgroundQueue'to make final callback 7840
00001389 261.20971680 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001390 261.41598511 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001391 261.41607666 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001392 261.53833008 [5336] Going 'BackgroundQueue'to make final callback 5444
00001393 261.53851318 [5336] Thread 'BackgroundQueue'has finished 5444
00001394 261.64086914 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001395 262.07296753 [5336] ReadExternalStream: Read = 65536, Offset = 393216, Size = 5251072, Portion = 0.07, File: C:\Users\Oliver Eichler\Music\In Extremo\Weckt Die Toten\In Extremo - Palästinalied.mp3
00001396 262.21041870 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001397 262.24932861 [5336] Going 'BackgroundQueue'to make final callback 9740
00001398 262.41735840 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001399 262.41748047 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00001400 262.64465332 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=23294.mp3
00001401 263.20840454 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00001402 263.41738892 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00001403 263.41744995 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
[...]
00004688 1026.02697754 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00004689 1026.02709961 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00004690 1026.02722168 [5336] Player: Next command started
00004691 1026.02734375 [5336] Player: Next track prepared, going to start playback
00004692 1026.02758789 [5336] PrepareDockedVideo: Switching=true IsStartingPlayback=false WasManuallyChanged=false
00004693 1026.02770996 [5336] Remaining Covers: 4
00004694 1026.02783203 [5336] BQ: Added new task. Currently 1 tasks in queue.
00004695 1026.02783203 [5336] BQ: Currently running 0 threads of 8
00004696 1026.04272461 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 0, All: 28
00004697 1026.04785156 [5336] 3472 DB open SQL: SELECT Lyrics, Comment FROM Songs WHERE Songs.ID=?
00004698 1026.04870605 [5336] Thread 'BackgroundQueue' has started 7644
00004699 1026.04895020 [5336] Added Cover Lists: 2
00004700 1026.04907227 [5336] GetFileInfo: readPictures=spAlways
00004701 1026.04919434 [5336] MP3.ReadHeader C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004702 1026.04931641 [5336] MP3.ReadHeader - Found ID3v2, length: 46427
00004703 1026.04943848 [5336] MP3.ReadHeader CheckMoreHeaders started
00004704 1026.04943848 [5336] MP3.ReadHeader CheckMoreHeaders finished
00004705 1026.04956055 [5336] MP3: Going to read tag from C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004706 1026.05187988 [5336] MP3: Tag read from C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004707 1026.05212402 [5336] 7644 DB query prepare finished, took 365.
00004708 1026.05236816 [5336] 7644 DB lock finished, took 237.
00004709 1026.05249023 [5336] UpdateSongPictures: CoverList.LoadForSongId, C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004710 1026.05261230 [5336] OpenQuery - Preparing query SELECT * FROM Covers WHERE IDSong=24336 ORDER BY CoverOrder
00004711 1026.05285645 [5336] Added Covers: 6
00004712 1026.05297852 [5336] Added Covers: 6
00004713 1026.18725586 [5336] StretchStart
00004714 1026.18750000 [5336] BQ: Starting a new task.
00004715 1026.18762207 [5336] BQ: Starting a new thread.
00004716 1026.20263672 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 1, All: 29
00004717 1026.20837402 [5336] Thread 'BackgroundQueue' has started 8536
00004718 1026.20922852 [5336] StretchEnd
00004719 1026.21276855 [5336] Remaining Covers: 6
00004720 1026.21289063 [5336] BQ: Starting a new task.
00004721 1026.45544434 [5336] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 2, All: 30
00004722 1026.46032715 [5336] BQ: Added new task. Currently 2 tasks in queue.
00004723 1026.46044922 [5336] BQ: There are threads waiting, resume one.
00004724 1026.46057129 [5336] BQ: Starting a new task.
00004725 1026.46057129 [5336] BQ: Added new task. Currently 1 tasks in queue.
00004726 1026.46069336 [5336] BQ: There are threads waiting, resume one.
00004727 1026.46069336 [5336] BQ: Starting a new task.
00004728 1026.46081543 [5336] BQ: Added new task. Currently 1 tasks in queue.
00004729 1026.46093750 [5336] BQ: There are threads waiting, resume one.
00004730 1026.46179199 [5336] Thread 'BackgroundQueue' has started 8048
00004731 1026.87353516 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00004732 1027.92541504 [5336] Winamp simulation window received messsage 12
00004733 1027.92553711 [5336] BQ: Starting a new task.
00004734 1027.93066406 [5336] Winamp simulation window received messsage 14
00004735 1027.93090820 [5336] PrepareDockedVideo: Begin
00004736 1027.93090820 [5336] PrepareDockedVideo: Reduced window or audio
00004737 1027.93103027 [5336] PrepareDockedVideo: End
00004738 1027.95361328 [5336] TGenThread.Create: ThreadName: PlaybackStarter_Var , Running Threads: Internal: 3, All: 33
00004739 1027.95837402 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00004740 1027.95837402 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00004741 1027.95971680 [5336] Player: _Play() procedure entering.
00004742 1027.96398926 [5336] Player: W7 icon updated.
00004743 1027.96411133 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00004744 1027.96423340 [5336] Player: Setting current track: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004745 1027.96435547 [5336] Player: Song is not cached, try to play the original file
00004746 1027.96459961 [5336] UPnP: Get IP Addresses
00004747 1027.98742676 [5336] GetNetworkAdapters finished: Count = 1
00004748 1027.98791504 [5336] 2044 DB query prepare finished, took 97.
00004749 1027.98803711 [5336] 2044 DB lock finished, took 222.
00004750 1027.98815918 [5336] GetProtocolInfoFromExt URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004751 1027.98828125 [5336] PLT_MimeType::GetMimeTypeFromExtension: No context !
00004752 1027.98840332 [5336] 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>Vogelfrei</dc:title><dc:date>2002-10-07</dc:date><upnp:artist role="Performer">Schandmaul</upnp:artist><upnp:artist role="AlbumArtist">Schandmaul</upnp:artist><upnp:album>Narrenkönig</upnp:album><upnp:genre></upnp:genre><upnp:albumArtURI dlna:profileID="JPEG_TN">http://192.168.178.11:55345/3076.jpg</upnp:albumArtURI><upnp:originalTrackNumber>9</upnp:originalTrackNumber><res duration="0:05:06.202" size="6170624" bitrate="160000" sampleFrequency="44100" nrAudioChannels="2" protocolInfo="http-get:*:audio/mpeg:DLNA.ORG_PN=MP3;DLNA.ORG_OP=01;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=01500000000000000000000000000000">http://192.168.178.11:55345/ItemID=24336.mp3</res><upnp:class>object.item.audioItem.musicTrack</upnp:class></item></DIDL-Lite>
00004753 1027.98852539 [5336] TRemotePlayer.InvokeAction(SetAVTransportURI, http://192.168.178.11:55345/ItemID=24336.mp3, )
00004754 1028.03442383 [5336] UPNP Server: File Request: /ItemID=24336.mp3 , Range: < -1, -1>
00004755 1028.03454590 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00004756 1028.03454590 [5336] PostponeComputerSleep: Enabled = 1
00004757 1028.03466797 [5336] OpenQuery - Preparing query SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00004758 1028.03527832 [5336] 9940 DB prepare SQL: SELECT * FROM Songs WHERE Songs.ID=?
00004759 1028.03552246 [5336] 9940 DB open SQL: SELECT * FROM Songs WHERE Songs.ID=?
00004760 1028.03588867 [5336] _BrowseRequest finished
00004761 1028.03601074 [5336] UPnP Server: Creating HTTP entity
00004762 1028.03881836 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004763 1028.05358887 [5336] UPnP: OnSetAVTransportURIResult
00004764 1028.05371094 [5336] TRemotePlayer.InvokeAction(Play, , )
00004765 1028.08947754 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004766 1028.14001465 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004767 1028.16796875 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00004768 1028.16796875 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00004769 1028.19055176 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004770 1028.24108887 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004771 1028.29162598 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004772 1028.30627441 [5336] UPNP Server: File Request: /ItemID=24336.mp3 , Range: < -1, -1>
00004773 1028.30627441 [5336] TMediaServer.Get(ea4381da-eabb-4b55-9061-446a736dd117)
00004774 1028.30639648 [5336] 4556 DB open SQL: SELECT * FROM RemoteClients WHERE MAC = '00-22-61-E7-38-9C'
00004775 1028.30688477 [5336] 4556 DB lock finished, took 902.
00004776 1028.30725098 [5336] 4556 DB query prepare finished, took 298.
00004777 1028.30737305 [5336] 4556 DB lock finished, took 212.
00004778 1028.30761719 [5336] UPnP Server: Serving file C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3, ExternalStream = 1, FileLen = 6170624
00004779 1028.54431152 [5336] TRemotePlayer.PlayURI: Waiting for playback start, URI: http://192.168.178.11:55345/ItemID=24336.mp3
00004780 1028.54443359 [5336] Player: Playback started
00004781 1028.54479980 [5336] DB: Deleting prepared query: SELECT NULL FROM Covers WHERE Covers.IDSong = ?
00004782 1028.54492188 [5336] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false
00004783 1028.54504395 [5336] PrepareDockedVideo: Audio
00004784 1028.54577637 [5336] ReadExternalStream: Read = 65536, Offset = 196608, Size = 6170624, Portion = 0.03, File: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004785 1028.55151367 [5336] UpdateSongPictures: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004786 1028.55163574 [5336] GetFileInfo: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004787 1028.55163574 [5336] GetFileInfo: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004788 1028.55187988 [5336] MP3.ReadHeader - File size 6170624
00004789 1028.55200195 [5336] MP3.ReadHeader framestarts: 46437, 46437
00004790 1028.55212402 [5336] MP3.ReadHeader CheckMoreHeaders Endtest: 49509
00004791 1028.55224609 [5336] MP3.ReadHeader finished
00004792 1028.55444336 [5336] Added Covers: 7
00004793 1028.55456543 [5336] 3472 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
00004794 1028.55493164 [5336] GetFileInfo: Found in DB
00004795 1028.55493164 [5336] 3472 DB open SQL: SELECT * FROM Covers WHERE IDSong=24336 ORDER BY CoverOrder
00004796 1028.55517578 [5336] 3472 DB lock finished, took 386.
00004797 1028.55529785 [5336] Remaining Covers: 7
00004798 1028.55541992 [5336] Added Covers: 9
00004799 1028.61340332 [5336] ReadExternalStream: Read = 65536, Offset = 262144, Size = 6170624, Portion = 0.04, File: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004800 1028.63049316 [5336] StretchStart
00004801 1028.63085938 [5336] BQ: Added new task. Currently 1 tasks in queue.
00004802 1028.63098145 [5336] BQ: There are threads waiting, resume one.
00004803 1028.63354492 [5336] Remaining Covers: 8
00004804 1028.63366699 [5336] Remaining Cover Lists: 2
00004805 1028.73437500 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004806 1028.73449707 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004807 1028.78430176 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004808 1028.79443359 [5336] UPnP: Control Request: Waiting...
00004809 1028.97485352 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004810 1029.02807617 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004811 1029.03869629 [5336] UPnP: Control Request: Waiting...
00004812 1029.03869629 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004813 1029.25537109 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004814 1029.52050781 [5336] Going 'BackgroundQueue'to make final callback 8536
00004815 1030.02673340 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004816 1030.02685547 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004817 1030.25329590 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004818 1030.92541504 [5336] Going 'BackgroundQueue'to make final callback 7644
00004819 1030.92553711 [5336] Thread 'BackgroundQueue'has finished 7644
00004820 1031.02807617 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004821 1031.02819824 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004822 1031.25439453 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00004823 1031.63342285 [5336] Going 'BackgroundQueue'to make final callback 8048
00004824 1031.91638184 [5336] ReadExternalStream: Read = 65536, Offset = 393216, Size = 6170624, Portion = 0.06, File: C:\Users\Oliver Eichler\Music\Schandmaul\Narrenkönig\Schandmaul - Vogelfrei.mp3
00004825 1032.02673340 [5336] UPnP: OnGetTransportInfoResponse: PLAYING
00004826 1032.02685547 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00004827 1032.23522949 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
[...]
00005926 1334.03540039 [5336] UPnP: Control Request: SetTransportStatus: PLAYING
00005927 1334.25964355 [5336] UPnP: OnGetPositionInfoResponse: http://192.168.178.11:55345/ItemID=24336.mp3
00005928 1334.81970215 [5336] TRemotePlayer.InvokeAction(GetTransportInfo, , )
00005929 1335.03686523 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005930 1335.03698730 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00005931 1335.03710938 [5336] Player: Next command started
00005932 1335.03723145 [5336] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false
00005933 1335.03735352 [5336] PrepareDockedVideo: Audio
00005934 1336.03674316 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005935 1336.03674316 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
[...]
00005948 1343.03857422 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005949 1343.03881836 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00005950 1343.14660645 [2592] base\appmodel\execmodel\modern\lifetimemanager\suspendresume.cpp(4263)\modernexecserver.dll!00007FFBFDDC102A: (caller: 00007FFBFDDA78E3) ReturnHr[PreRelease](144) tid(24cc) 8000000D Eine unzulässige Statusänderung wurde angefordert.
00005951 1344.03588867 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005952 1344.03613281 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
[...]
00005959 1348.03503418 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005960 1348.03527832 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
00005961 1348.22595215 [5336] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 26
00005962 1348.23156738 [5336] Thread 'UpdateDriveLetters_Var' has started 6424
00005963 1348.23498535 [5336] >UpdateDriveLetters - end<
00005964 1348.23510742 [5336] Thread 'UpdateDriveLetters_Var'has finished 6424
00005965 1349.03503418 [5336] UPnP: OnGetTransportInfoResponse: STOPPED
00005966 1349.03527832 [5336] UPnP: Control Request: SetTransportStatus: STOPPED
[...]
[/code]
I condensed this log to 3 mp3 files. The original log contains 5 files. I've cut out many lines which seemed to be obsolete to the problem.
I played 5 files. File #1, #3 and #5 have "full" information about the release date of the album ("DD.MM.YYYY"). Song #2 and #4 have only the year of release ("YYYY"). For #1, #3 and #5 all file information is correctly transmitted and displayed on my renderer. #2 and #4 lead only to displaying the already mentioned network path. I found some command lines where it says "found ID3v2". This line only shows up for files #1, #3 and #5 and can be found in line 00000046, 00002860 and 00004702. Maybe this helps to find a solution?