@brian: Happened again two minutes ago
Serverlog shows (only last lines and replaced some private infos with ***):
02/07 00:19:48 Trace: [prebuffer] status 441000/441000 (100%) @ 150/240 sec
02/07 00:19:53 Trace: [prebuffer] status 441000/441000 (100%) @ 155/240 sec
02/07 00:19:58 Trace: [prebuffer] status 441000/441000 (100%) @ 160/240 sec
02/07 00:19:58 Info: [stats] 1412mb Virtual, 450mb Physical, 263mb Managed, 1489 Handles, 64 Threads
02/07 00:20:03 Trace: [prebuffer] status 441000/441000 (100%) @ 165/240 sec
02/07 00:20:08 Trace: [prebuffer] status 441000/441000 (100%) @ 170/240 sec
02/07 00:20:13 Trace: [prebuffer] status 441000/441000 (100%) @ 175/240 sec
02/07 00:20:13 Info: [stats] 1404mb Virtual, 450mb Physical, 264mb Managed, 1484 Handles, 62 Threads
02/07 00:20:18 Trace: [prebuffer] status 441000/441000 (100%) @ 180/240 sec
02/07 00:20:23 Trace: [prebuffer] status 441000/441000 (100%) @ 185/240 sec
02/07 00:20:28 Trace: [prebuffer] status 441000/441000 (100%) @ 190/240 sec
02/07 00:20:28 Info: [stats] 1416mb Virtual, 466mb Physical, 279mb Managed, 1513 Handles, 65 Threads
02/07 00:20:33 Trace: [prebuffer] status 441000/441000 (100%) @ 195/240 sec
02/07 00:20:38 Trace: [prebuffer] status 441000/441000 (100%) @ 200/240 sec
02/07 00:20:43 Trace: [prebuffer] status 441000/441000 (100%) @ 205/240 sec
02/07 00:20:43 Info: [stats] 1416mb Virtual, 420mb Physical, 218mb Managed, 1478 Handles, 65 Threads
02/07 00:20:48 Trace: [prebuffer] status 441000/441000 (100%) @ 210/240 sec
02/07 00:20:53 Trace: [prebuffer] status 441000/441000 (100%) @ 215/240 sec
02/07 00:20:58 Trace: [prebuffer] status 441000/441000 (100%) @ 220/240 sec
02/07 00:20:58 Info: [stats] 1412mb Virtual, 422mb Physical, 230mb Managed, 1477 Handles, 64 Threads
02/07 00:21:02 Warn: [prebuffer] in buffer threadSystem.Exception: Read failure: UnknownFailure
bei Sooloos.Audio.MediaDecoderAudioSignal.Read(Byte[] buffer, Int32 offset, Int32 frames)
bei Sooloos.Audio.SeekableBufferedAudioSignal._Buffer(Int32 buffer_seq)
bei Sooloos.Audio.SeekableBufferedAudioSignal.<>c__DisplayClass6.<_StartBuffering>b__4()
02/07 00:21:02 Info: [zoneplayer/base] Track ended unexpectedly: Sooloos.Audio.BufferedReadException: error durring buffered read ---> System.Exception: Read failure: UnknownFailure
bei Sooloos.Audio.MediaDecoderAudioSignal.Read(Byte[] buffer, Int32 offset, Int32 frames)
bei Sooloos.Audio.SeekableBufferedAudioSignal._Buffer(Int32 buffer_seq)
bei Sooloos.Audio.SeekableBufferedAudioSignal.<>c__DisplayClass6.<_StartBuffering>b__4()
--- Ende der internen Ausnahmestapelüberwachung ---
bei Sooloos.Audio.SeekableBufferedAudioSignal.Read(Byte[] buffer, Int32 offset, Int32 frames)
bei Sooloos.Broker.Transport.ZonePlayerTrack._Stream.ReadImp(AudioBuffer buf, Int32 nsamples)
bei Sooloos.Audio.AudioStream.Read(AudioBuffer buf, Int32 nsamples)
bei Sooloos.Broker.Transport.ZonePlayerBase.ReadImp(AudioBuffer buf, Int32 nsamples)
02/07 00:21:02 Warn: Track Stopped Due to Error
02/07 00:21:02 Info: [library] recorded play for profile ***: mediaid=50:1:3051a838-da9f-4fff-adb3-dc02eb79848b metadataid= contentid=168:0:1793384 libraryid=50:1:3051a838-da9f-4fff-adb3-dc02eb79848b
02/07 00:21:02 Trace: [library] finished with 201 dirty tracks 10 dirty albums 25 dirty performers 152 dirty works 190 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 372 changed objects
02/07 00:21:02 Debug: [library/index] updating search indices: 16 ops 0 adds, 0 removes
02/07 00:21:02 Trace: [library] endmutation in 35ms
02/07 00:21:02 Info: [zoneplayer/base] Playing: D:\Musik\CDs\Blackmore's Night\Blackmore's Night - Autumn Sky\07 Blackmore's Night - Celluloid Heroes.mp3
02/07 00:21:02 Trace: [airplay/client] Wohnzimmer (192.168.178.38:5000) Sending FLUSH
02/07 00:21:02 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
02/07 00:21:02 Info: [zoneplayer/base] Queueing: D:\Musik\CDs\Patricia Kaas\Patricia Kaas - Je te dis vous\05 Patricia Kaas - Il me dit que je suis belle.mp3
02/07 00:21:02 Trace: [airplay/client] Wohnzimmer (192.168.178.38:5000) FLUSH was successful
02/07 00:21:02 Info: [zoneplayer/base] Open Result (Playing):Result[Status=Success]
02/07 00:21:02 Info: [zoneplayer/base] Starting playback
02/07 00:21:02 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
Source Format=Mp3 44100/16/2 BitRate=320 Quality=LowQuality
UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless
VolumeNormaliation GainDb=-10,31 Quality=Enhanced
Truncate FromBitsPerSample=64 ToBitsPerSample=16 Quality=HighQuality
Output OutputType=AirPlay Quality=HighQuality
------------------------------------------------------------
02/07 00:21:02 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
Source Format=Mp3 44100/16/2 BitRate=320 Quality=LowQuality
UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless
VolumeNormaliation GainDb=0 Quality=Lossless
Truncate FromBitsPerSample=64 ToBitsPerSample=16 Quality=HighQuality
Output OutputType=AirPlay Quality=HighQuality
------------------------------------------------------------
02/07 00:21:02 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/327 sec
02/07 00:21:02 Trace: [prebuffer] status 149940/441000 (34%) @ 0/327 sec
02/07 00:21:02 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
Source Format=Mp3 44100/16/2 BitRate=320 Quality=LowQuality
UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless
VolumeNormaliation GainDb=-3,84330123257338 Quality=Enhanced
Truncate FromBitsPerSample=64 ToBitsPerSample=16 Quality=HighQuality
Output OutputType=AirPlay Quality=HighQuality
------------------------------------------------------------
02/07 00:21:02 Trace: [airplay/client] Wohnzimmer (192.168.178.38:5000) Sending first sync packet rtptime=639676622 synctime=639764822
02/07 00:21:03 Info: [zoneplayer/base] Open result (Queueing): Result[Status=Success]
02/07 00:21:03 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/321 sec
02/07 00:21:07 Trace: [zone] [Airplay Wohnzimmer] PlayPause
02/07 00:21:07 Trace: [zone] [Airplay Wohnzimmer] Pause
02/07 00:21:07 Trace: [airplay/client] Wohnzimmer (192.168.178.38:5000) Sending FLUSH
02/07 00:21:07 Trace: [airplay/client] Wohnzimmer (192.168.178.38:5000) FLUSH was successful
02/07 00:21:12 Trace: [zone] no playback for 5s, suspending to release audio device
02/07 00:21:12 Trace: [airplay/client] Wohnzimmer (192.168.178.38:5000) Sending TEARDOWN
02/07 00:21:12 Trace: [zone] [Airplay Wohnzimmer] Suspend
02/07 00:21:12 Trace: [zone] [Airplay Wohnzimmer] Stop
02/07 00:21:12 Trace: [airplay/client] Wohnzimmer (192.168.178.38:5000) Sending FLUSH
02/07 00:21:12 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
02/07 00:21:12 Trace: [airplay/client] Wohnzimmer (192.168.178.38:5000) TEARDOWN was successful
02/07 00:21:12 Warn: [airplay/client] Wohnzimmer (192.168.178.38:5000) FLUSH failed: 455 Method Not Valid In This State
02/07 00:21:12 Info: [transport/airplay] AirPlay device disconnected: AirPlayDevice[DeviceId=EC112741553A@Wohnzimmer._raop._tcp.local, Name=Wohnzimmer.local, Model=R-N602, IPEndPoint=192.168.178.38:5000]
02/07 00:21:12 Trace: [transport/airplay] disconnected
02/07 00:21:12 Trace: [zone] Airplay Wohnzimmer received transport control from endpoint integration: suspend
02/07 00:21:12 Trace: [zone] [Airplay Wohnzimmer] Airplay Wohnzimmer received transport control from Wohnzimmer: suspend
02/07 00:21:12 Trace: [zone] [Airplay Wohnzimmer] Suspend
02/07 00:21:13 Info: [stats] 1416mb Virtual, 421mb Physical, 231mb Managed, 1488 Handles, 65 Threads
02/07 00:21:28 Info: [stats] 1404mb Virtual, 422mb Physical, 232mb Managed, 1485 Handles, 62 Threads
02/07 00:21:43 Info: [stats] 1375mb Virtual, 421mb Physical, 234mb Managed, 1474 Handles, 54 Threads
02/07 00:21:54 Trace: [broker/accounts] refreshing account info for ***
02/07 00:21:54 Trace: [broker/accounts] booted up with token *** userid ***
02/07 00:21:54 Trace: [fiveaccountserver] POST https://accounts5.roonlabs.com/accounts/3/login
02/07 00:21:54 Trace: [fiveaccountserver] BODY token=***
02/07 00:21:55 Trace: Successful POST response from https://accounts5.roonlabs.com/accounts/3/login
02/07 00:21:55 Trace: [fiveaccountserver] GOT {"status":"Success","userid":"***","token":"***","expiration":30}
02/07 00:21:55 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/profileslist?token=***
02/07 00:21:55 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/userinfo?token=***
02/07 00:21:55 Trace: [broker/accounts] updated token. New expiration is 09.03.2017 00:21:54
02/07 00:21:55 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=***
02/07 00:21:55 Trace: [push] restarting connection (Von der Übertragungsverbindung können keine Daten gelesen werden: Ein Blockierungsvorgang wurde durch einen Aufruf von WSACancelBlockingCall unterbrochen.)
02/07 00:21:55 Trace: [push] retrying connection in 74298ms
02/07 00:21:55 Trace: [fiveaccountserver] GOT {"status":"Success","profiles":[{"id":"***","name":"***","birthdate":"***"}]}
02/07 00:21:55 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=***
02/07 00:21:55 Trace: [fiveaccountserver] GOT {"status":"Success","user":{"privileges":0,"userid":"***","firstname":"***","lastname":"***","email":"***","joinmailinglist":true,"class":"Normal","trialallowed":false}}
02/07 00:21:55 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=***
02/07 00:21:58 Info: [stats] 1376mb Virtual, 422mb Physical, 234mb Managed, 1488 Handles, 55 Threads
02/07 00:22:05 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
02/07 00:22:05 Trace: [push] connecting to ***
02/07 00:22:05 Trace: [push] connected