Skip to content

Instantly share code, notes, and snippets.

@joeczucha
Created December 7, 2020 09:48
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save joeczucha/52d7a2bd99a242a4322e9042c5a70045 to your computer and use it in GitHub Desktop.
Save joeczucha/52d7a2bd99a242a4322e9042c5a70045 to your computer and use it in GitHub Desktop.
12/07 09:18:53 Trace: [client/sonos] av_status
12/07 09:18:53 Trace: [Dining room] [zoneplayer/sonos] state from device: PLAYING
12/07 09:18:53 Trace: [Dining room] [zoneplayer/sonos] zoneplayer state: Playing
12/07 09:18:53 Trace: [client/sonos] av_status
12/07 09:18:53 Trace: [Dining room] [zoneplayer/sonos] state from device: PLAYING
12/07 09:18:53 Trace: [Dining room] [zoneplayer/sonos] zoneplayer state: Playing
12/07 09:18:53 Warn: [Dining room] [zoneplayer/sonos] position timer reported previous url, fix for bug 16980 triggered
12/07 09:18:53 Trace: [Dining room] [zoneplayer/sonos] Switching to next stream path
12/07 09:18:54 Info: [stats] 4977mb Virtual, 2581mb Physical, 1485mb Managed, 0 Handles, 95 Threads
12/07 09:18:59 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [PLAYING @ 11:39] RTE Radio 1
12/07 09:19:01 Info: SKIP ID3 73 bytes
12/07 09:19:01 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-342,"seek_position":702}]}
12/07 09:19:01 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-346,"seek_position":706}]}
12/07 09:19:02 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-349,"seek_position":709}]}
12/07 09:19:04 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [PLAYING @ 11:49] RTE Radio 1
12/07 09:19:09 Info: [stats] 4977mb Virtual, 2582mb Physical, 1499mb Managed, 0 Handles, 95 Threads
12/07 09:19:10 Info: SKIP ID3 73 bytes
12/07 09:19:10 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [PLAYING @ 11:49] RTE Radio 1
12/07 09:19:10 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-350,"seek_position":710}]}
12/07 09:19:10 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-358,"seek_position":718}]}
12/07 09:19:11 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-359,"seek_position":719}]}
12/07 09:19:17 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [PLAYING @ 11:59] RTE Radio 1
12/07 09:19:22 Info: SKIP ID3 73 bytes
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] transaction canceled, isplaying: True, did stream end: False, tx path: /15541faf600a4931bdd3c826795aa6cb/Roon73.flac, stream path: /15541faf600a4931bdd3c826795aa6cb/Roon73.flac, method: Get
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] transaction canceled, queue new stream
12/07 09:19:22 Trace: [client/sonos] next uri request
12/07 09:19:22 Trace: [client/sonos] next uri response
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] request matches stream, current path: /15541faf600a4931bdd3c826795aa6cb/Roon73.flac, next path: /86d42863eb4f466faf5bfc79bcebb2e6/Roon74.flac
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] request matches stream, current path: /15541faf600a4931bdd3c826795aa6cb/Roon73.flac, next path: /86d42863eb4f466faf5bfc79bcebb2e6/Roon74.flac
12/07 09:19:22 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-362,"seek_position":722}]}
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] transaction canceled, isplaying: True, did stream end: False, tx path: /86d42863eb4f466faf5bfc79bcebb2e6/Roon74.flac, stream path: /15541faf600a4931bdd3c826795aa6cb/Roon73.flac, method: Get
12/07 09:19:22 Trace: [client/sonos] av_status
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] state from device: PLAYING
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] zoneplayer state: Playing
12/07 09:19:22 Trace: [client/sonos] av_status
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] state from device: PLAYING
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] zoneplayer state: Playing
12/07 09:19:22 Warn: [Dining room] [zoneplayer/sonos] position timer reported previous url, fix for bug 16980 triggered
12/07 09:19:22 Trace: [Dining room] [zoneplayer/sonos] Switching to next stream path
12/07 09:19:22 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [PLAYING @ 12:03] RTE Radio 1
12/07 09:19:23 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-366,"seek_position":726}]}
12/07 09:19:23 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-369,"seek_position":729}]}
12/07 09:19:24 Info: [stats] 4977mb Virtual, 2582mb Physical, 1526mb Managed, 0 Handles, 95 Threads
12/07 09:19:31 Info: SKIP ID3 73 bytes
12/07 09:19:31 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [PLAYING @ 12:09] RTE Radio 1
12/07 09:19:31 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-370,"seek_position":730}]}
12/07 09:19:32 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-375,"seek_position":735}]}
12/07 09:19:32 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-379,"seek_position":739}]}
12/07 09:19:39 Info: [stats] 4977mb Virtual, 2582mb Physical, 1535mb Managed, 0 Handles, 95 Threads
12/07 09:19:40 Info: SKIP ID3 73 bytes
12/07 09:19:40 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [PLAYING @ 12:19] RTE Radio 1
12/07 09:19:40 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-380,"seek_position":740}]}
12/07 09:19:41 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-381,"seek_position":741}]}
12/07 09:19:41 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-389,"seek_position":749}]}
12/07 09:19:49 Info: SKIP ID3 73 bytes
12/07 09:19:49 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [PLAYING @ 12:29] RTE Radio 1
12/07 09:19:49 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-390,"seek_position":750}]}
12/07 09:19:50 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":-399,"seek_position":759}]}
12/07 09:19:54 Info: [stats] 4977mb Virtual, 2582mb Physical, 1562mb Managed, 0 Handles, 95 Threads
12/07 09:20:00 Trace: [client/sonos] av_status
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] state from device: STOPPED
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] zoneplayer state: Playing
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] device stopped or paused by user, ending stream
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] State transition Playing => Disconnected
12/07 09:20:00 Trace: [endpoint/sonos/uuid:RINCON_347E5CF125BA01400] check zone coordinator, device coordinator: RINCON_347E5CF125BA01400, roon coordinator: RINCON_347E5CF125BA01400
12/07 09:20:00 Trace: [client/sonos] timers stopped
12/07 09:20:00 Warn: [zone Dining room] Track Stopped Due to LostEndpoint
12/07 09:20:00 Trace: Attempting to play channel URL: hls://https%3a%2f%2fwww.rte.ie%2fmanifests%2fradio1.m3u8
12/07 09:20:00 Info: [Dining room] [zoneplayer] Playing: channel://hls%3a%2f%2fhttps%253a%252f%252fwww.rte.ie%252fmanifests%252fradio1.m3u8
12/07 09:20:00 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [PLAYING @ 12:39] RTE Radio 1
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] endstream, zp state: Disconnected
12/07 09:20:00 Info: [Dining room] [zoneplayer] advance didn't change the track. returning short read
12/07 09:20:00 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
12/07 09:20:00 Info: [zone Dining room] OnPlayFeedback StoppedLostEndpoint
12/07 09:20:00 Trace: [zone Dining room] Suspend
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] reached end of stream, closing connection
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] transaction canceled, isplaying: False, did stream end: True, tx path: /86d42863eb4f466faf5bfc79bcebb2e6/Roon74.flac, stream path: /86d42863eb4f466faf5bfc79bcebb2e6/Roon74.flac, method: Get
12/07 09:20:00 Info: [zone Dining room] OnPlayFeedback Stopped
12/07 09:20:00 Info: [zone Dining room] Canceling Pending Sleep
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] endstream, zp state: Disconnected
12/07 09:20:00 Info: [zone Dining room] Canceling Pending Sleep
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] endstream, zp state: Disconnected
12/07 09:20:00 Trace: [Dining room] [zoneplayer/sonos] endstream, zp state: Disconnected
12/07 09:20:00 Trace: [Dining room] [LowQuality, 24/44 AAC => 16/44] [100% buf] [STOPPED @ 0:00] RTE Radio 1
12/07 09:20:00 Trace: [roonapi] [apiclient 10.10.10.111:45236] CONTINUE Changed {"zones_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","display_name":"Dining room","outputs":[{"output_id":"170108540d2a69d8ea46b513e5c73f9aa156","zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","can_group_with_output_ids":["170108540d2a69d8ea46b513e5c73f9aa156","1701cee07a64e1de0d7bd9a941d18df41827","170157fce0867df0bf4787a0b427313fe98c","170153b9bd9f118053a5cca8ca03b219e935"],"display_name":"Dining room","volume":{"type":"number","min":0,"max":100,"value":22,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":[{"control_key":"1","display_name":"SYMFONISK (Dining Room)","supports_standby":false,"status":"indeterminate"}]}],"state":"stopped","is_next_allowed":false,"is_previous_allowed":false,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":false,"queue_items_remaining":1,"queue_time_remaining":360,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"one_line":{"line1":"RTE Radio 1"},"two_line":{"line1":"RTE Radio 1","line2":""},"three_line":{"line1":"RTE Radio 1","line2":"","line3":""},"image_key":"afd611ddf80b5dce68aa13b8e9e99aff8bf23d351d0918d67f032e52c91c6dc24882ed6107bcb78f714d78cf03afae00f38e3b8e823c3c6dd3cb71f675a91481f1c07c9518261b3af42728d0343bff634d98380aad3997c33d35cb0a3bc32763139f6c5db22951dc9ff0e30e822296fd"}}],"zones_seek_changed":[{"zone_id":"160108540d2a69d8ea46b513e5c73f9aa156","queue_time_remaining":360,"seek_position":null}]}
12/07 09:20:00 Debug: [easyhttp] [2218] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack returned after 228 ms, status code: 200
12/07 09:20:01 Info: SKIP ID3 73 bytes
12/07 09:20:01 Debug: [easyhttp] [2220] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack returned after 179 ms, status code: 200
12/07 09:20:01 Debug: [easyhttp] [2219] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack returned after 483 ms, status code: 200
12/07 09:20:01 Debug: [easyhttp] [2221] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack returned after 215 ms, status code: 400
12/07 09:20:01 Info: [Dining room] [zoneplayer] Open Result (Playing):Result[Status=Success]
12/07 09:20:01 Info: [Dining room] [zoneplayer] Aborting play because track changed
12/07 09:20:01 Warn: [streammediafile] error reading stream: Cannot access a closed Stream.
12/07 09:20:01 Error: BufferedAudioSignal thread refuses to die
12/07 09:20:01 Info: [audio/env] [zoneplayer] All streams were disposed
12/07 09:20:01 Warn: [streammediafile] error reading stream: Cannot access a closed Stream.
12/07 09:20:09 Info: [stats] 4995mb Virtual, 2588mb Physical, 1576mb Managed, 0 Handles, 93 Threads
12/07 09:20:21 Trace: [broker/accounts] [heartbeat] now=12/07/2020 09:20:21 nextauthrefresh=12/07/2020 10:02:03 nextmachineallocate=12/07/2020 09:50:21
12/07 09:20:24 Info: [stats] 4995mb Virtual, 2589mb Physical, 1583mb Managed, 0 Handles, 86 Threads
12/07 09:20:39 Info: [stats] 4995mb Virtual, 2589mb Physical, 1584mb Managed, 0 Handles, 85 Threads
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment