petelindeck@Petes-iMac ~ % tail -f ~/Library/RoonServer/Logs/RoonServer_log.txt | grep -i "eversolo\|dmp\|192.168.132.95\|input\|transport\|raat"
^C
petelindeck@Petes-iMac ~ % grep "07/09.*17:12" ~/Library/RoonServer/Logs/RoonServer_log.txt
07/09 17:12:05 Info: [stats] 427982mb Virtual; 817mb Physical = 631mb GC-committed (593mb Managed-live = 93% of committed) + 186mb Native; 0.1% of runtime in GC pauses, 0ms GC pause in last window (0% of window)
07/09 17:12:20 Info: [stats] 427985mb Virtual; 817mb Physical = 631mb GC-committed (596mb Managed-live = 94% of committed) + 186mb Native; 0.1% of runtime in GC pauses, 15ms GC pause in last window (0.1% of window)
07/09 17:12:35 Info: [stats] 427990mb Virtual; 818mb Physical = 631mb GC-committed (595mb Managed-live = 94% of committed) + 187mb Native; 0.1% of runtime in GC pauses, 8ms GC pause in last window (0.06% of window)
07/09 17:12:50 Info: [stats] 427990mb Virtual; 556mb Physical = 631mb GC-committed (593mb Managed-live = 93% of committed) + -75mb Native; 0.1% of runtime in GC pauses, 26ms GC pause in last window (0.18% of window)
petelindeck@Petes-iMac ~ % grep "07/09 17:1" ~/Library/RoonServer/Logs/RoonServer_log.txt | grep -i "eversolo\|dmp\|192.168.132.95\|input\|zone\|transport\|stop\|pause\|raat"
07/09 17:10:00 Trace: [DMP-A6] [Enhanced, 16/44 FLAC => 32/44] [100% buf] [PLAYING @ 4:02/4:30] Lightning & Thunder - Jhené Aiko / John Legend
07/09 17:10:05 Trace: [DMP-A6] [Enhanced, 16/44 FLAC => 32/44] [100% buf] [PLAYING @ 4:07/4:30] Lightning & Thunder - Jhené Aiko / John Legend
07/09 17:10:05 Info: [stats] 427997mb Virtual; 946mb Physical = 630mb GC-committed (557mb Managed-live = 88% of committed) + 316mb Native; 0.1% of runtime in GC pauses, 10ms GC pause in last window (0.07% of window)
07/09 17:10:10 Trace: [DMP-A6] [Enhanced, 16/44 FLAC => 32/44] [100% buf] [PLAYING @ 4:12/4:30] Lightning & Thunder - Jhené Aiko / John Legend
07/09 17:10:15 Trace: [DMP-A6] [Enhanced, 16/44 FLAC => 32/44] [66% buf] [PLAYING @ 4:17/4:30] Lightning & Thunder - Jhené Aiko / John Legend
07/09 17:10:20 Trace: [DMP-A6] [Enhanced, 16/44 FLAC => 32/44] [20% buf] [PLAYING @ 4:23/4:30] Lightning & Thunder - Jhené Aiko / John Legend
07/09 17:10:20 Info: [stats] 428002mb Virtual; 946mb Physical = 630mb GC-committed (558mb Managed-live = 88% of committed) + 316mb Native; 0.1% of runtime in GC pauses, 20ms GC pause in last window (0.14% of window)
07/09 17:10:20 Trace: [DMP-A6] [zoneplayer/raat] sync Eversolo DMP-A6: realtime=9029571148038 rtt=24607us offset=4067389144us delta=25201us drift=34740us in 578.42935885s (60.061ppm, 216.219ms/hr)
07/09 17:10:25 Trace: [DMP-A6] [Enhanced, 16/44 FLAC => 32/44] [4% buf] [PLAYING @ 4:28/4:30] Lightning & Thunder - Jhené Aiko / John Legend
07/09 17:10:27 Trace: [DMP-A6] [zoneplayer/raat] Endpoint Eversolo DMP-A6 State Changed: Playing => Prepared
07/09 17:10:27 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] SENT [5993]{"request":"end_stream"}
07/09 17:10:27 Info: [zone DMP-A6] [zone] Creating new play record: playId=639191850276503090, seconds=270
07/09 17:10:27 Info: [zone DMP-A6] [zone] Skipping play record update: playId=639191850276503090, seconds=0 < recorded 270
07/09 17:10:27 Info: [zone DMP-A6] OnPlayFeedback StoppedEndOfMediaNatural
07/09 17:10:27 Debug: [zone DMP-A6] _Advance (Track)
07/09 17:10:27 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] GOT [5404] {"status":"Ended"}
07/09 17:10:27 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] GOT [5993] {"status":"Success"}
07/09 17:10:27 Trace: [DMP-A6] [Enhanced, 16/44 FLAC => 32/44] [4% buf] [LOADING @ 0:00] I've Seen It - Olivia Dean
07/09 17:10:27 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] SENT [5995]{"request":"update_artwork","mimetype":"image/jpeg"}
07/09 17:10:27 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] GOT [5995] {"status":"Success"}
07/09 17:10:27 Info: [zone DMP-A6] waveform loaded for track 6371122: 2000 samples (attempt 1)
07/09 17:10:27 Info: [DMP-A6] [zoneplayer] Playing: /Users/petelindeck/Library/RoonMounts/RoonStorage_fd84aeff30dbc1c8ff0de90cbbb21eeef1673c70/media/music/Olivia Dean - (2025) The Art of Loving/olivia dean - the art of loving - 12 - i've seen it.flac
07/09 17:10:27 Debug: [raat/tcpaudiosource] disconnecting
07/09 17:10:27 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
07/09 17:10:27 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
07/09 17:10:27 Info: [audio/env] [zoneplayer] All streams were disposed
07/09 17:10:28 Info: [DMP-A6] [zoneplayer] Queueing: /Users/petelindeck/Library/RoonMounts/RoonStorage_fd84aeff30dbc1c8ff0de90cbbb21eeef1673c70/media/music/Erykah Badu - (2015) But You Caint Use My Phone/erykah badu - but you caint use my phone - 4 - phone down.flac
07/09 17:10:28 Info: [DMP-A6] [zoneplayer]     Open Result (Playing):Result[Status=Success]
07/09 17:10:28 Info: [DMP-A6] [zoneplayer] Starting playback
07/09 17:10:28 Trace: [DMP-A6] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=24, samplerate=44100, isdts=False) => StreamFormat(channels=2, bitspersample=32, samplerate=44100, isdts=False) streamid=48323362
07/09 17:10:28 Info: [zone DMP-A6] [zone] LL_PlaybackStarted: fresh start, no suspended or persisted state. trackId=6371122
07/09 17:10:28 Info: [zone DMP-A6] OnPlayFeedback Playing
07/09 17:10:28 Trace: [DMP-A6] [Lossless, 24/44 FLAC => 32/44] [4% buf] [PLAYING @ 0:00] I've Seen It - Olivia Dean
07/09 17:10:28 Trace: [DMP-A6] [zoneplayer/raat] synced to endpoint Eversolo DMP-A6 clock: realtime=9036987574091 rtt=7863us offset=4067352700us delta=4067352700us
07/09 17:10:28 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] SENT [5999]{"request":"stream","stream_id":48323362,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
07/09 17:10:28 Info: [DMP-A6] [zoneplayer] Open result (Queueing): Result[Status=Success]
07/09 17:10:28 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] GOT [5999] {"status":"Buffering"}
07/09 17:10:28 Trace: [DMP-A6] [zoneplayer/raat] Endpoint Eversolo DMP-A6 State Changed: Prepared => Buffering
07/09 17:10:28 Trace: [DMP-A6] [zoneplayer/raat] [zoneplayer/raat] selected Eversolo DMP-A6 as clock master
07/09 17:10:28 Debug: [raat/tcpaudiosource] connecting to 192.168.132.95:33621
07/09 17:10:28 Debug: [raat/tcpaudiosource] connected
07/09 17:10:28 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] GOT [5999] {"status":"Ready"}
07/09 17:10:28 Trace: [DMP-A6] [zoneplayer/raat] Endpoint Eversolo DMP-A6 State Changed: Buffering => Ready
07/09 17:10:28 Trace: [DMP-A6] [zoneplayer/raat] wait for ready in 225ms
07/09 17:10:28 Trace: [DMP-A6] [zoneplayer/raat] Adjusting playback start offset from 50ms to 281ms
07/09 17:10:28 Trace: [DMP-A6] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
07/09 17:10:28 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] SENT [6000]{"request":"start","min_offset":281921768,"stream_sample":0}
07/09 17:10:28 Trace: [DMP-A6] [zoneplayer/raat] Endpoint Eversolo DMP-A6 State Changed: Ready => Playing
07/09 17:10:28 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] GOT [5999] {"status":"Playing"}
07/09 17:10:28 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] GOT [6000] {"status":"Success","time":9037657846948}
07/09 17:10:33 Trace: [DMP-A6] [Enhanced, 24/44 FLAC => 32/44] [100% buf] [PLAYING @ 0:04/2:06] I've Seen It - Olivia Dean
07/09 17:10:35 Info: [stats] 428007mb Virtual; 959mb Physical = 633mb GC-committed (585mb Managed-live = 92% of committed) + 326mb Native; 0.1% of runtime in GC pauses, 75ms GC pause in last window (0.51% of window)
07/09 17:10:38 Trace: [DMP-A6] [Enhanced, 24/44 FLAC => 32/44] [100% buf] [PLAYING @ 0:09/2:06] I've Seen It - Olivia Dean
07/09 17:10:43 Trace: [DMP-A6] [Enhanced, 24/44 FLAC => 32/44] [100% buf] [PLAYING @ 0:15/2:06] I've Seen It - Olivia Dean
07/09 17:10:48 Trace: [DMP-A6] [Enhanced, 24/44 FLAC => 32/44] [100% buf] [PLAYING @ 0:20/2:06] I've Seen It - Olivia Dean
07/09 17:10:50 Info: [stats] 428007mb Virtual; 959mb Physical = 633mb GC-committed (589mb Managed-live = 93% of committed) + 326mb Native; 0.1% of runtime in GC pauses, 15ms GC pause in last window (0.1% of window)
07/09 17:10:53 Trace: [DMP-A6] [Enhanced, 24/44 FLAC => 32/44] [100% buf] [PLAYING @ 0:25/2:06] I've Seen It - Olivia Dean
07/09 17:10:58 Trace: [DMP-A6] [zoneplayer/raat] sync Eversolo DMP-A6: realtime=9067575314957 rtt=11533us offset=4067378493us delta=11073us drift=25792us in 30.5464624s (844.365ppm, 3039.714ms/hr)
07/09 17:10:58 Trace: [DMP-A6] [Enhanced, 24/44 FLAC => 32/44] [100% buf] [PLAYING @ 0:30/2:06] I've Seen It - Olivia Dean
07/09 17:11:00 Warn: [DMP-A6] [zoneplayer/raat] long rtt sync Eversolo DMP-A6: realtime=9069654970794 rtt=86635us offset=4067344491us delta=-34001us drift=-8209us in 32.6028082s (-251.797ppm, -906.470ms/hr)
07/09 17:11:00 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] GOT [5402] {"status":"Lost","reason":{"reason":"source_deselected"}}
07/09 17:11:00 Trace: [Eversolo DMP-A6 @ 192.168.132.95:40923] [raatclient] GOT [5] {"action":"Changed","status":"Success","controls":{"source":{"value":"not_selected","info":{"config":{}}},"volume":{"info":{"config":{}},"value":0,"mute":false,"min":0.0,"step":1.0,"type":"none","max":0.0}}}
07/09 17:11:00 Trace: [DMP-A6] [zoneplayer/raat] Got 'lost' response
07/09 17:11:00 Trace: [DMP-A6] [zoneplayer/raat] Endpoint Eversolo DMP-A6 State Changed: Playing => Idle
07/09 17:11:00 Trace: [DMP-A6] [zoneplayer/raat] lost endpoint. stopping stream
07/09 17:11:00 Debug: [raat/tcpaudiosource] disconnecting
07/09 17:11:00 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
07/09 17:11:00 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
07/09 17:11:00 Warn: [zone DMP-A6] Track Stopped Due to LostEndpoint
07/09 17:11:00 Info: [zone DMP-A6] [zone] Creating new play record: playId=639191850610717030, seconds=32
07/09 17:11:00 Info: [zone DMP-A6] OnPlayFeedback StoppedLostEndpoint
07/09 17:11:00 Trace: [zone DMP-A6] Suspend
07/09 17:11:00 Info: [zone DMP-A6] [zone] Skipping play record update: playId=639191850610717030, seconds=0 < recorded 32
07/09 17:11:00 Info: [zone DMP-A6] OnPlayFeedback Stopped
07/09 17:11:00 Info: [zone DMP-A6] Canceling Pending Sleep
07/09 17:11:01 Trace: [DMP-A6] [Enhanced, 24/44 FLAC => 32/44] [100% buf] [PAUSED @ 0:32/2:06] I've Seen It - Olivia Dean
07/09 17:11:01 Info: [audio/env] [zoneplayer] All streams were disposed
07/09 17:11:05 Info: [stats] 427997mb Virtual; 961mb Physical = 633mb GC-committed (594mb Managed-live = 93% of committed) + 328mb Native; 0.1% of runtime in GC pauses, 54ms GC pause in last window (0.36% of window)
07/09 17:11:20 Info: [stats] 427990mb Virtual; 817mb Physical = 631mb GC-committed (591mb Managed-live = 93% of committed) + 186mb Native; 0.1% of runtime in GC pauses, 13ms GC pause in last window (0.09% of window)
07/09 17:11:35 Info: [stats] 427991mb Virtual; 817mb Physical = 631mb GC-committed (592mb Managed-live = 93% of committed) + 186mb Native; 0.1% of runtime in GC pauses, 6ms GC pause in last window (0.05% of window)
07/09 17:11:50 Info: [stats] 427990mb Virtual; 818mb Physical = 631mb GC-committed (592mb Managed-live = 93% of committed) + 187mb Native; 0.1% of runtime in GC pauses, 14ms GC pause in last window (0.1% of window)
07/09 17:12:05 Info: [stats] 427982mb Virtual; 817mb Physical = 631mb GC-committed (593mb Managed-live = 93% of committed) + 186mb Native; 0.1% of runtime in GC pauses, 0ms GC pause in last window (0% of window)
07/09 17:12:20 Info: [stats] 427985mb Virtual; 817mb Physical = 631mb GC-committed (596mb Managed-live = 94% of committed) + 186mb Native; 0.1% of runtime in GC pauses, 15ms GC pause in last window (0.1% of window)
07/09 17:12:35 Info: [stats] 427990mb Virtual; 818mb Physical = 631mb GC-committed (595mb Managed-live = 94% of committed) + 187mb Native; 0.1% of runtime in GC pauses, 8ms GC pause in last window (0.06% of window)
07/09 17:12:50 Info: [stats] 427990mb Virtual; 556mb Physical = 631mb GC-committed (593mb Managed-live = 93% of committed) + -75mb Native; 0.1% of runtime in GC pauses, 26ms GC pause in last window (0.18% of window)
07/09 17:13:05 Info: [stats] 427982mb Virtual; 556mb Physical = 631mb GC-committed (595mb Managed-live = 94% of committed) + -75mb Native; 0.1% of runtime in GC pauses, 0ms GC pause in last window (0% of window)
07/09 17:13:20 Info: [stats] 427987mb Virtual; 556mb Physical = 631mb GC-committed (594mb Managed-live = 94% of committed) + -75mb Native; 0.1% of runtime in GC pauses, 16ms GC pause in last window (0.11% of window)
07/09 17:13:35 Info: [stats] 427990mb Virtual; 556mb Physical = 631mb GC-committed (593mb Managed-live = 93% of committed) + -75mb Native; 0.1% of runtime in GC pauses, 8ms GC pause in last window (0.06% of window)
07/09 17:13:50 Info: [stats] 427991mb Virtual; 556mb Physical = 631mb GC-committed (596mb Managed-live = 94% of committed) + -75mb Native; 0.1% of runtime in GC pauses, 13ms GC pause in last window (0.09% of window)
07/09 17:14:05 Info: [stats] 427980mb Virtual; 556mb Physical = 631mb GC-committed (589mb Managed-live = 93% of committed) + -75mb Native; 0.1% of runtime in GC pauses, 8ms GC pause in last window (0.06% of window)
07/09 17:14:20 Info: [stats] 427985mb Virtual; 556mb Physical = 631mb GC-committed (596mb Managed-live = 94% of committed) + -75mb Native; 0.1% of runtime in GC pauses, 16ms GC pause in last window (0.11% of window)
07/09 17:14:35 Info: [stats] 427988mb Virtual; 761mb Physical = 631mb GC-committed (510mb Managed-live = 80% of committed) + 130mb Native; 0.1% of runtime in GC pauses, 11ms GC pause in last window (0.08% of window)
07/09 17:14:37 Trace: [zone DMP-A6] Pause
07/09 17:14:43 Trace: [zone DMP-A6] Pause
07/09 17:14:45 Trace: [zone DMP-A6] Pause
07/09 17:14:46 Trace: [zone DMP-A6] Pause
07/09 17:14:50 Info: [stats] 427988mb Virtual; 762mb Physical = 631mb GC-committed (511mb Managed-live = 80% of committed) + 131mb Native; 0.1% of runtime in GC pauses, 15ms GC pause in last window (0.11% of window)
07/09 17:14:51 Trace: [zone DMP-A6] Pause
07/09 17:14:56 Trace: [zone DMP-A6] Pause
07/09 17:14:57 Trace: [zone DMP-A6] Pause
07/09 17:15:05 Info: [stats] 427983mb Virtual; 762mb Physical = 631mb GC-committed (512mb Managed-live = 81% of committed) + 131mb Native; 0.1% of runtime in GC pauses, 0ms GC pause in last window (0% of window)
07/09 17:15:20 Info: [stats] 427986mb Virtual; 762mb Physical = 631mb GC-committed (511mb Managed-live = 80% of committed) + 131mb Native; 0.1% of runtime in GC pauses, 14ms GC pause in last window (0.1% of window)
07/09 17:15:35 Info: [stats] 427988mb Virtual; 762mb Physical = 631mb GC-committed (507mb Managed-live = 80% of committed) + 131mb Native; 0.1% of runtime in GC pauses, 11ms GC pause in last window (0.08% of window)
07/09 17:15:50 Info: [stats] 427988mb Virtual; 762mb Physical = 631mb GC-committed (507mb Managed-live = 80% of committed) + 131mb Native; 0.1% of runtime in GC pauses, 13ms GC pause in last window (0.09% of window)
07/09 17:16:05 Info: [stats] 427982mb Virtual; 762mb Physical = 631mb GC-committed (508mb Managed-live = 80% of committed) + 131mb Native; 0.1% of runtime in GC pauses, 0ms GC pause in last window (0% of window)
07/09 17:16:20 Info: [stats] 427985mb Virtual; 762mb Physical = 629mb GC-committed (509mb Managed-live = 80% of committed) + 133mb Native; 0.1% of runtime in GC pauses, 16ms GC pause in last window (0.11% of window)
07/09 17:16:35 Info: [stats] 427991mb Virtual; 762mb Physical = 629mb GC-committed (509mb Managed-live = 80% of committed) + 133mb Native; 0.1% of runtime in GC pauses, 5ms GC pause in last window (0.04% of window)
07/09 17:16:50 Info: [stats] 427991mb Virtual; 762mb Physical = 629mb GC-committed (508mb Managed-live = 80% of committed) + 133mb Native; 0.1% of runtime in GC pauses, 11ms GC pause in last window (0.07% of window)
07/09 17:17:05 Info: [stats] 427980mb Virtual; 762mb Physical = 629mb GC-committed (513mb Managed-live = 81% of committed) + 133mb Native; 0.1% of runtime in GC pauses, 0ms GC pause in last window (0% of window)
07/09 17:17:20 Info: [stats] 427985mb Virtual; 762mb Physical = 629mb GC-committed (512mb Managed-live = 81% of committed) + 133mb Native; 0.1% of runtime in GC pauses, 20ms GC pause in last window (0.14% of window)
07/09 17:17:35 Info: [stats] 427989mb Virtual; 763mb Physical = 629mb GC-committed (513mb Managed-live = 81% of committed) + 134mb Native; 0.1% of runtime in GC pauses, 7ms GC pause in last window (0.05% of window)
07/09 17:17:50 Info: [stats] 427989mb Virtual; 762mb Physical = 628mb GC-committed (510mb Managed-live = 81% of committed) + 134mb Native; 0.1% of runtime in GC pauses, 14ms GC pause in last window (0.09% of window)
07/09 17:18:05 Info: [stats] 427982mb Virtual; 762mb Physical = 628mb GC-committed (513mb Managed-live = 81% of committed) + 134mb Native; 0.1% of runtime in GC pauses, 0ms GC pause in last window (0% of window)
07/09 17:18:20 Info: [stats] 427986mb Virtual; 762mb Physical = 628mb GC-committed (511mb Managed-live = 81% of committed) + 134mb Native; 0.1% of runtime in GC pauses, 13ms GC pause in last window (0.09% of window)
07/09 17:18:35 Info: [stats] 427989mb Virtual; 717mb Physical = 628mb GC-committed (510mb Managed-live = 81% of committed) + 89mb Native; 0.1% of runtime in GC pauses, 4ms GC pause in last window (0.03% of window)
07/09 17:18:50 Info: [stats] 427993mb Virtual; 717mb Physical = 628mb GC-committed (513mb Managed-live = 81% of committed) + 89mb Native; 0.1% of runtime in GC pauses, 19ms GC pause in last window (0.13% of window)
07/09 17:19:05 Info: [stats] 427982mb Virtual; 717mb Physical = 628mb GC-committed (515mb Managed-live = 82% of committed) + 89mb Native; 0.1% of runtime in GC pauses, 0ms GC pause in last window (0% of window)
07/09 17:19:20 Info: [stats] 427986mb Virtual; 717mb Physical = 628mb GC-committed (510mb Managed-live = 81% of committed) + 89mb Native; 0.1% of runtime in GC pauses, 21ms GC pause in last window (0.15% of window)
07/09 17:19:35 Info: [stats] 427991mb Virtual; 716mb Physical = 628mb GC-committed (507mb Managed-live = 80% of committed) + 88mb Native; 0.1% of runtime in GC pauses, 10ms GC pause in last window (0.07% of window)
07/09 17:19:50 Info: [stats] 427991mb Virtual; 716mb Physical = 627mb GC-committed (508mb Managed-live = 81% of committed) + 89mb Native; 0.1% of runtime in GC pauses, 9ms GC pause in last window (0.06% of window)
petelindeck@Petes-iMac ~ % grep -i "input switched\|input\|switched" ~/Library/RoonServer/Logs/RoonServer_log.txt
07/09 13:41:34 Info: [roonapi] audioinput is supported
petelindeck@Petes-iMac ~ %