gronod/Ensemble
1
0
Fork 0
Code Issues Pull Requests 1 Actions Packages Projects Releases Wiki Activity
Files
master
Ensemble/docs/remote-connection/webrtc-failure-logs.txt
T
ChrisandClaude Opus 4.5 633f489bfa Add remote connection documentation 
Documents WebRTC remote connection investigation and findings.
Feature on hold due to one-way channel bug. See docs for details.

Co-Authored-By: Claude Opus 4.5 <noreply@anthropic.com>
2026-01-24 09:19:20 +00:00

1000 lines
117 KiB
Plaintext
Raw Permalink Blame History

[07:14:20.820] INFO 📥 Got 100 albums
[07:14:20.820] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:20.821] INFO WebRTC: Raw message: {"message_id":"863339ff-053f-460d-b7d8-a371a6ffe5c2","result":{"queue_id":"sendspin-cli-dining-room-hifi","active":true,"display_name":"Dining Room Hi-Fi","available":true,"items":14,"shuffle_enabled"...
[07:14:20.821] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 863339ff-053f-460d-b7d8-a371a6ffe5c2
[07:14:20.821] INFO WebRTC: No match for message_id=863339ff-053f-460d-b7d8-a371a6ffe5c2, pending: []
[07:14:20.821] INFO RemoteBridge: WebRTC API → WS: {"message_id":"863339ff-053f-460d-b7d8-a371a6ffe5c2","result":{"queue_id":"sendspin-cli-dining-room-...
[07:14:20.822] INFO Received message: (message_id, result, partial)
[07:14:20.822] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:20.822] INFO RemoteBridge: API WS → WebRTC: {"message_id":"d519803b-d75a-4ac0-8721-c51839ef9676","command":"player_queues/items","args":{"queue_...
[07:14:20.822] INFO WebRTC: Sending raw: {"message_id":"d519803b-d75a-4ac0-8721-c51839ef9676","command":"player_queues/items","args":{"queue_...
[07:14:20.822] INFO RemoteBridge: API WS → WebRTC: {"message_id":"e74e3291-90a9-4f7e-9d8b-bd19909fb313","command":"player_queues/items","args":{"queue_...
[07:14:20.822] INFO WebRTC: Sending raw: {"message_id":"e74e3291-90a9-4f7e-9d8b-bd19909fb313","command":"player_queues/items","args":{"queue_...
[07:14:20.822] INFO WebRTC: Raw message: {"message_id":"e30d036e-59f0-40c4-9cad-95cb32a89bac","result":{"queue_id":"20:F8:3B:09:50:6A","active":true,"display_name":"Home Assistant Voice","available":true,"items":0,"shuffle_enabled":false,"re...
[07:14:20.822] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: e30d036e-59f0-40c4-9cad-95cb32a89bac
[07:14:20.822] INFO WebRTC: No match for message_id=e30d036e-59f0-40c4-9cad-95cb32a89bac, pending: []
[07:14:20.822] INFO RemoteBridge: WebRTC API → WS: {"message_id":"e30d036e-59f0-40c4-9cad-95cb32a89bac","result":{"queue_id":"20:F8:3B:09:50:6A","activ...
[07:14:20.822] INFO Received message: (message_id, result, partial)
[07:14:20.822] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:20.822] INFO RemoteBridge: API WS → WebRTC: {"message_id":"ef2e2a93-7a87-4c52-8494-0b8d263cd9a7","command":"player_queues/items","args":{"queue_...
[07:14:20.822] INFO WebRTC: Sending raw: {"message_id":"ef2e2a93-7a87-4c52-8494-0b8d263cd9a7","command":"player_queues/items","args":{"queue_...
[07:14:20.822] INFO WebRTC: Raw message: {"message_id":"c265db69-5333-47c0-9a39-6c1e74b914e0","result":{"queue_id":"7df484e3-d2ee-c897-f746-2dffc29595ff","active":true,"display_name":"Shield","available":true,"items":0,"shuffle_enabled":fals...
[07:14:20.822] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: c265db69-5333-47c0-9a39-6c1e74b914e0
[07:14:20.822] INFO WebRTC: No match for message_id=c265db69-5333-47c0-9a39-6c1e74b914e0, pending: []
[07:14:20.822] INFO RemoteBridge: WebRTC API → WS: {"message_id":"c265db69-5333-47c0-9a39-6c1e74b914e0","result":{"queue_id":"7df484e3-d2ee-c897-f746-2...
[07:14:20.822] INFO Received message: (message_id, result, partial)
[07:14:20.822] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:20.822] INFO RemoteBridge: API WS → WebRTC: {"message_id":"613ae50e-8b5a-49dc-9f3e-ed539459e8e2","command":"player_queues/items","args":{"queue_...
[07:14:20.822] INFO WebRTC: Sending raw: {"message_id":"613ae50e-8b5a-49dc-9f3e-ed539459e8e2","command":"player_queues/items","args":{"queue_...
[07:14:20.891] INFO WebRTC: Raw message: {"message_id":"d519803b-d75a-4ac0-8721-c51839ef9676","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:20.894] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: d519803b-d75a-4ac0-8721-c51839ef9676
[07:14:20.894] INFO WebRTC: No match for message_id=d519803b-d75a-4ac0-8721-c51839ef9676, pending: []
[07:14:20.894] INFO RemoteBridge: WebRTC API → WS: {"message_id":"d519803b-d75a-4ac0-8721-c51839ef9676","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:20.898] INFO Received message: (message_id, result, partial)
[07:14:20.899] INFO 🔍 Preload Chris' Phone: queue=true, currentItem=true
[07:14:20.899] INFO 🔍 Preload Chris' Phone: CACHED track "Matthew & Son"
[07:14:20.901] INFO WebRTC: Raw message: {"message_id":"e74e3291-90a9-4f7e-9d8b-bd19909fb313","result":[{"queue_id":"sendspin-cli-dining-room-hifi","queue_item_id":"7d444bdeae9e4648bb5c0168484b8ebc","name":"Neil Young - Tell Me Why","duratio...
[07:14:20.902] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: e74e3291-90a9-4f7e-9d8b-bd19909fb313
[07:14:20.902] INFO WebRTC: No match for message_id=e74e3291-90a9-4f7e-9d8b-bd19909fb313, pending: []
[07:14:20.902] INFO RemoteBridge: WebRTC API → WS: {"message_id":"e74e3291-90a9-4f7e-9d8b-bd19909fb313","result":[{"queue_id":"sendspin-cli-dining-room...
[07:14:20.903] INFO WebRTC: Raw message: {"message_id":"613ae50e-8b5a-49dc-9f3e-ed539459e8e2","result":[],"partial":false}...
[07:14:20.903] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 613ae50e-8b5a-49dc-9f3e-ed539459e8e2
[07:14:20.903] INFO WebRTC: No match for message_id=613ae50e-8b5a-49dc-9f3e-ed539459e8e2, pending: []
[07:14:20.903] INFO RemoteBridge: WebRTC API → WS: {"message_id":"613ae50e-8b5a-49dc-9f3e-ed539459e8e2","result":[],"partial":false}...
[07:14:20.908] INFO Received message: (message_id, result, partial)
[07:14:20.908] INFO Received message: (message_id, result, partial)
[07:14:20.909] INFO 🔍 Preload Dining Room Hi-Fi: queue=true, currentItem=true
[07:14:20.909] INFO 🔍 Preload Dining Room Hi-Fi: CACHED track "Tell Me Why"
[07:14:20.909] INFO ⚠️ Queue is empty or all items failed to parse
[07:14:20.909] INFO 🔍 Preload Shield: queue=false, currentItem=false
[07:14:20.909] INFO 🔍 Preload Shield: NO TRACK - queue empty
[07:14:20.909] INFO WebRTC: Raw message: {"message_id":"ef2e2a93-7a87-4c52-8494-0b8d263cd9a7","result":[],"partial":false}...
[07:14:20.909] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: ef2e2a93-7a87-4c52-8494-0b8d263cd9a7
[07:14:20.909] INFO WebRTC: No match for message_id=ef2e2a93-7a87-4c52-8494-0b8d263cd9a7, pending: []
[07:14:20.909] INFO RemoteBridge: WebRTC API → WS: {"message_id":"ef2e2a93-7a87-4c52-8494-0b8d263cd9a7","result":[],"partial":false}...
[07:14:20.910] INFO Received message: (message_id, result, partial)
[07:14:20.910] INFO ⚠️ Queue is empty or all items failed to parse
[07:14:20.910] INFO 🔍 Preload Home Assistant Voice: queue=false, currentItem=false
[07:14:20.910] INFO 🔍 Preload Home Assistant Voice: NO TRACK - queue empty
[07:14:20.948] INFO 💾 Persisted 22 queue items to database
[07:14:20.962] INFO 💾 Persisted 14 queue items to database
[07:14:21.321] INFO 🔍 API getArtists args: {limit: 100, album_artists_only: false}
[07:14:21.324] INFO RemoteBridge: API WS → WebRTC: {"message_id":"6328e6e9-f315-4c5a-9c2a-16aade2f6152","command":"music/artists/library_items","args":...
[07:14:21.324] INFO WebRTC: Sending raw: {"message_id":"6328e6e9-f315-4c5a-9c2a-16aade2f6152","command":"music/artists/library_items","args":...
[07:14:21.494] INFO WebRTC: Raw message: {"message_id":"6328e6e9-f315-4c5a-9c2a-16aade2f6152","result":[{"item_id":"65","provider":"library","name":"10cc","version":"","sort_name":"10cc","uri":"library://artist/65","external_ids":[],"is_play...
[07:14:21.499] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 6328e6e9-f315-4c5a-9c2a-16aade2f6152
[07:14:21.499] INFO WebRTC: No match for message_id=6328e6e9-f315-4c5a-9c2a-16aade2f6152, pending: []
[07:14:21.499] INFO RemoteBridge: WebRTC API → WS: {"message_id":"6328e6e9-f315-4c5a-9c2a-16aade2f6152","result":[{"item_id":"65","provider":"library",...
[07:14:21.510] INFO Received message: (message_id, result, partial)
[07:14:21.510] INFO 🔍 API getArtists returned 100 items
[07:14:21.510] INFO 📥 Got 100 artists
[07:14:22.011] INFO 📚 getAudiobooks: absProviderIds={}, providerInstanceIds=null
[07:14:22.013] INFO RemoteBridge: API WS → WebRTC: {"message_id":"2f55aa60-9a5d-49bc-a551-16898e83a3d3","command":"music/audiobooks/library_items","arg...
[07:14:22.013] INFO WebRTC: Sending raw: {"message_id":"2f55aa60-9a5d-49bc-a551-16898e83a3d3","command":"music/audiobooks/library_items","arg...
[07:14:22.088] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:22.088] INFO ⏱️ PositionTracker: Anchor updated 16.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=2.0, suspiciousReset=false, anchorStale=false)
[07:14:22.089] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:22.089] INFO ⏱️ PositionTracker: Emitting position 14s (anchor=14.0s, playing=false)
[07:14:22.090] INFO 📊 Progress received position: 14s
[07:14:22.091] INFO ⏱️ Starting idle service timer (30 min)
[07:14:22.091] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:22.091] INFO 🔍 Preload Shield Guest: state=idle, available=true
[07:14:22.091] INFO 🔍 Preload Shield Guest: SKIPPED - not available or powered
[07:14:22.091] INFO 🔍 Preload Dining Room Hi-Fi: state=idle, available=true
[07:14:22.092] INFO RemoteBridge: API WS → WebRTC: {"message_id":"3685841c-8b0c-4927-af0b-51cefbf3208a","command":"players/all"}...
[07:14:22.092] INFO WebRTC: Sending raw: {"message_id":"3685841c-8b0c-4927-af0b-51cefbf3208a","command":"players/all"}...
[07:14:22.092] INFO RemoteBridge: API WS → WebRTC: {"message_id":"003be4af-a2dd-48a1-b021-e0cf66fc78b7","command":"player_queues/get","args":{"queue_id...
[07:14:22.092] INFO WebRTC: Sending raw: {"message_id":"003be4af-a2dd-48a1-b021-e0cf66fc78b7","command":"player_queues/get","args":{"queue_id...
[07:14:22.092] INFO RemoteBridge: API WS → WebRTC: {"message_id":"7f0c9ba9-5b62-4e21-ad0f-3de43ae10015","command":"players/all"}...
[07:14:22.092] INFO WebRTC: Sending raw: {"message_id":"7f0c9ba9-5b62-4e21-ad0f-3de43ae10015","command":"players/all"}...
[07:14:22.246] INFO WebRTC: Raw message: {"message_id":"2f55aa60-9a5d-49bc-a551-16898e83a3d3","result":[{"item_id":"522","provider":"library","name":"1001 Nights","version":"","sort_name":"1001 nights","uri":"library://audiobook/522","extern...
[07:14:22.257] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 2f55aa60-9a5d-49bc-a551-16898e83a3d3
[07:14:22.258] INFO WebRTC: No match for message_id=2f55aa60-9a5d-49bc-a551-16898e83a3d3, pending: []
[07:14:22.258] INFO RemoteBridge: WebRTC API → WS: {"message_id":"2f55aa60-9a5d-49bc-a551-16898e83a3d3","result":[{"item_id":"522","provider":"library"...
[07:14:22.269] INFO WebRTC: Raw message: {"message_id":"3685841c-8b0c-4927-af0b-51cefbf3208a","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:14:22.269] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 3685841c-8b0c-4927-af0b-51cefbf3208a
[07:14:22.269] INFO WebRTC: No match for message_id=3685841c-8b0c-4927-af0b-51cefbf3208a, pending: []
[07:14:22.269] INFO RemoteBridge: WebRTC API → WS: {"message_id":"3685841c-8b0c-4927-af0b-51cefbf3208a","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:14:22.276] INFO Received message: (message_id, result, partial)
[07:14:22.276] INFO Received message: (message_id, result, partial)
[07:14:22.277] INFO 📚 Got 100 audiobooks from library_items API
[07:14:22.277] INFO 📚 Total audiobooks: 100
[07:14:22.277] INFO 📥 Got 100 audiobooks
[07:14:22.277] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:22.277] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:14:22.277] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:22.277] INFO WebRTC: Raw message: {"message_id":"003be4af-a2dd-48a1-b021-e0cf66fc78b7","result":{"queue_id":"sendspin-cli-dining-room-hifi","active":true,"display_name":"Dining Room Hi-Fi","available":true,"items":14,"shuffle_enabled"...
[07:14:22.278] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 003be4af-a2dd-48a1-b021-e0cf66fc78b7
[07:14:22.278] INFO WebRTC: No match for message_id=003be4af-a2dd-48a1-b021-e0cf66fc78b7, pending: []
[07:14:22.278] INFO RemoteBridge: WebRTC API → WS: {"message_id":"003be4af-a2dd-48a1-b021-e0cf66fc78b7","result":{"queue_id":"sendspin-cli-dining-room-...
[07:14:22.278] INFO Received message: (message_id, result, partial)
[07:14:22.278] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:22.278] INFO RemoteBridge: API WS → WebRTC: {"message_id":"cc4a6fac-a10c-4f65-bd05-34fd9f655d35","command":"player_queues/get","args":{"queue_id...
[07:14:22.278] INFO WebRTC: Sending raw: {"message_id":"cc4a6fac-a10c-4f65-bd05-34fd9f655d35","command":"player_queues/get","args":{"queue_id...
[07:14:22.278] INFO RemoteBridge: API WS → WebRTC: {"message_id":"2e121990-ce0f-424d-a31c-42eda95f0509","command":"player_queues/items","args":{"queue_...
[07:14:22.278] INFO WebRTC: Sending raw: {"message_id":"2e121990-ce0f-424d-a31c-42eda95f0509","command":"player_queues/items","args":{"queue_...
[07:14:22.278] INFO WebRTC: Raw message: {"message_id":"7f0c9ba9-5b62-4e21-ad0f-3de43ae10015","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:14:22.278] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 7f0c9ba9-5b62-4e21-ad0f-3de43ae10015
[07:14:22.278] INFO WebRTC: No match for message_id=7f0c9ba9-5b62-4e21-ad0f-3de43ae10015, pending: []
[07:14:22.278] INFO RemoteBridge: WebRTC API → WS: {"message_id":"7f0c9ba9-5b62-4e21-ad0f-3de43ae10015","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:14:22.279] INFO Received message: (message_id, result, partial)
[07:14:22.279] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:22.279] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:14:22.279] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:22.279] INFO RemoteBridge: API WS → WebRTC: {"message_id":"b88e3900-956f-4ca7-894e-1a6622748c8d","command":"player_queues/get","args":{"queue_id...
[07:14:22.279] INFO WebRTC: Sending raw: {"message_id":"b88e3900-956f-4ca7-894e-1a6622748c8d","command":"player_queues/get","args":{"queue_id...
[07:14:22.307] INFO WebRTC: Raw message: {"message_id":"cc4a6fac-a10c-4f65-bd05-34fd9f655d35","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:14:22.307] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: cc4a6fac-a10c-4f65-bd05-34fd9f655d35
[07:14:22.307] INFO WebRTC: No match for message_id=cc4a6fac-a10c-4f65-bd05-34fd9f655d35, pending: []
[07:14:22.307] INFO RemoteBridge: WebRTC API → WS: {"message_id":"cc4a6fac-a10c-4f65-bd05-34fd9f655d35","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:14:22.308] INFO Received message: (message_id, result, partial)
[07:14:22.308] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:22.309] INFO RemoteBridge: API WS → WebRTC: {"message_id":"244575b3-98eb-4095-b0de-e3f150eca6d6","command":"player_queues/items","args":{"queue_...
[07:14:22.309] INFO WebRTC: Sending raw: {"message_id":"244575b3-98eb-4095-b0de-e3f150eca6d6","command":"player_queues/items","args":{"queue_...
[07:14:22.313] INFO WebRTC: Raw message: {"message_id":"b88e3900-956f-4ca7-894e-1a6622748c8d","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:14:22.314] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: b88e3900-956f-4ca7-894e-1a6622748c8d
[07:14:22.314] INFO WebRTC: No match for message_id=b88e3900-956f-4ca7-894e-1a6622748c8d, pending: []
[07:14:22.314] INFO RemoteBridge: WebRTC API → WS: {"message_id":"b88e3900-956f-4ca7-894e-1a6622748c8d","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:14:22.315] INFO Received message: (message_id, result, partial)
[07:14:22.315] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:22.315] INFO RemoteBridge: API WS → WebRTC: {"message_id":"027f6bf2-4756-497a-806e-2e8fa870f294","command":"player_queues/items","args":{"queue_...
[07:14:22.315] INFO WebRTC: Sending raw: {"message_id":"027f6bf2-4756-497a-806e-2e8fa870f294","command":"player_queues/items","args":{"queue_...
[07:14:22.345] INFO WebRTC: Raw message: {"message_id":"2e121990-ce0f-424d-a31c-42eda95f0509","result":[{"queue_id":"sendspin-cli-dining-room-hifi","queue_item_id":"7d444bdeae9e4648bb5c0168484b8ebc","name":"Neil Young - Tell Me Why","duratio...
[07:14:22.346] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 2e121990-ce0f-424d-a31c-42eda95f0509
[07:14:22.346] INFO WebRTC: No match for message_id=2e121990-ce0f-424d-a31c-42eda95f0509, pending: []
[07:14:22.346] INFO RemoteBridge: WebRTC API → WS: {"message_id":"2e121990-ce0f-424d-a31c-42eda95f0509","result":[{"queue_id":"sendspin-cli-dining-room...
[07:14:22.350] INFO Received message: (message_id, result, partial)
[07:14:22.351] INFO 🔍 Preload Dining Room Hi-Fi: queue=true, currentItem=true
[07:14:22.351] INFO 🔍 Preload Dining Room Hi-Fi: CACHED track "Tell Me Why"
[07:14:22.364] INFO WebRTC: Raw message: {"message_id":"244575b3-98eb-4095-b0de-e3f150eca6d6","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:22.366] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 244575b3-98eb-4095-b0de-e3f150eca6d6
[07:14:22.366] INFO WebRTC: No match for message_id=244575b3-98eb-4095-b0de-e3f150eca6d6, pending: []
[07:14:22.366] INFO RemoteBridge: WebRTC API → WS: {"message_id":"244575b3-98eb-4095-b0de-e3f150eca6d6","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:22.370] INFO Received message: (message_id, result, partial)
[07:14:22.371] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:22.380] INFO WebRTC: Raw message: {"message_id":"027f6bf2-4756-497a-806e-2e8fa870f294","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:22.381] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 027f6bf2-4756-497a-806e-2e8fa870f294
[07:14:22.381] INFO WebRTC: No match for message_id=027f6bf2-4756-497a-806e-2e8fa870f294, pending: []
[07:14:22.381] INFO RemoteBridge: WebRTC API → WS: {"message_id":"027f6bf2-4756-497a-806e-2e8fa870f294","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:22.382] INFO Received message: (message_id, result, partial)
[07:14:22.383] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:22.404] INFO 💾 Persisted 14 queue items to database
[07:14:22.436] INFO 💾 Persisted 22 queue items to database
[07:14:22.450] INFO 💾 Persisted 22 queue items to database
[07:14:22.451] INFO Sendspin: Sending message: client/state
[07:14:22.452] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:14:22.782] INFO RemoteBridge: API WS → WebRTC: {"message_id":"374878d9-993b-4353-adeb-51c23d338433","command":"music/playlists/library_items","args...
[07:14:22.782] INFO WebRTC: Sending raw: {"message_id":"374878d9-993b-4353-adeb-51c23d338433","command":"music/playlists/library_items","args...
[07:14:22.832] INFO WebRTC: Raw message: {"message_id":"374878d9-993b-4353-adeb-51c23d338433","result":[{"item_id":"14","provider":"library","name":"00.Info","version":"","sort_name":"00.info","uri":"library://playlist/14","external_ids":[],...
[07:14:22.833] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 374878d9-993b-4353-adeb-51c23d338433
[07:14:22.833] INFO WebRTC: No match for message_id=374878d9-993b-4353-adeb-51c23d338433, pending: []
[07:14:22.834] INFO RemoteBridge: WebRTC API → WS: {"message_id":"374878d9-993b-4353-adeb-51c23d338433","result":[{"item_id":"14","provider":"library",...
[07:14:22.839] INFO Received message: (message_id, result, partial)
[07:14:22.839] INFO 📥 Got 17 playlists
[07:14:22.839] INFO 📥 Total: 100 albums, 100 artists, 100 audiobooks, 17 playlists
[07:14:22.866] INFO 💾 Batch saved 100 albums to cache
[07:14:22.895] INFO 💾 Batch saved 100 artists to cache
[07:14:22.915] INFO 💾 Batch saved 100 audiobooks to cache
[07:14:22.926] INFO 💾 Batch saved 17 playlists to cache
[07:14:22.943] INFO ✅ Library sync complete
[07:14:22.943] INFO 📊 Source tracking: 0 albums, 0 artists, 0 audiobooks have provider info
[07:14:23.866] INFO Signaling: Sending ping
[07:14:23.905] INFO Signaling: Received message type: pong
[07:14:25.033] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:51 recv:54, Sendspin sent:4 recv:3, lastRecv:2s ago, state:RemoteBridgeState.connected
[07:14:27.098] INFO RemoteBridge: API WS → WebRTC: {"message_id":"a95b61c3-870e-4970-a0eb-d6de8f30b7b3","command":"players/all"}...
[07:14:27.099] INFO WebRTC: Sending raw: {"message_id":"a95b61c3-870e-4970-a0eb-d6de8f30b7b3","command":"players/all"}...
[07:14:27.135] INFO WebRTC: Raw message: {"message_id":"a95b61c3-870e-4970-a0eb-d6de8f30b7b3","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:14:27.137] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: a95b61c3-870e-4970-a0eb-d6de8f30b7b3
[07:14:27.137] INFO WebRTC: No match for message_id=a95b61c3-870e-4970-a0eb-d6de8f30b7b3, pending: []
[07:14:27.137] INFO RemoteBridge: WebRTC API → WS: {"message_id":"a95b61c3-870e-4970-a0eb-d6de8f30b7b3","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:14:27.145] INFO Received message: (message_id, result, partial)
[07:14:27.145] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:27.145] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:14:27.146] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:27.147] INFO RemoteBridge: API WS → WebRTC: {"message_id":"c44437a0-b499-498c-863b-6059ff22db4f","command":"player_queues/get","args":{"queue_id...
[07:14:27.147] INFO WebRTC: Sending raw: {"message_id":"c44437a0-b499-498c-863b-6059ff22db4f","command":"player_queues/get","args":{"queue_id...
[07:14:27.175] INFO WebRTC: Raw message: {"message_id":"c44437a0-b499-498c-863b-6059ff22db4f","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:14:27.176] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: c44437a0-b499-498c-863b-6059ff22db4f
[07:14:27.176] INFO WebRTC: No match for message_id=c44437a0-b499-498c-863b-6059ff22db4f, pending: []
[07:14:27.176] INFO RemoteBridge: WebRTC API → WS: {"message_id":"c44437a0-b499-498c-863b-6059ff22db4f","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:14:27.180] INFO Received message: (message_id, result, partial)
[07:14:27.181] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:27.183] INFO RemoteBridge: API WS → WebRTC: {"message_id":"82059d1c-765a-4a9c-9a13-1e32d1aa9af4","command":"player_queues/items","args":{"queue_...
[07:14:27.183] INFO WebRTC: Sending raw: {"message_id":"82059d1c-765a-4a9c-9a13-1e32d1aa9af4","command":"player_queues/items","args":{"queue_...
[07:14:27.265] INFO WebRTC: Raw message: {"message_id":"82059d1c-765a-4a9c-9a13-1e32d1aa9af4","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:27.274] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 82059d1c-765a-4a9c-9a13-1e32d1aa9af4
[07:14:27.275] INFO WebRTC: No match for message_id=82059d1c-765a-4a9c-9a13-1e32d1aa9af4, pending: []
[07:14:27.275] INFO RemoteBridge: WebRTC API → WS: {"message_id":"82059d1c-765a-4a9c-9a13-1e32d1aa9af4","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:27.283] INFO Received message: (message_id, result, partial)
[07:14:27.285] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:27.328] INFO 💾 Persisted 22 queue items to database
[07:14:27.453] INFO Sendspin: Sending message: client/state
[07:14:27.456] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:14:30.480] INFO WebRTC: ICE connection state: RTCIceConnectionState.RTCIceConnectionStateCompleted
[07:14:32.098] INFO RemoteBridge: API WS → WebRTC: {"message_id":"e96e2971-8aa5-43e4-a16c-c3fea51767d9","command":"players/all"}...
[07:14:32.099] INFO WebRTC: Sending raw: {"message_id":"e96e2971-8aa5-43e4-a16c-c3fea51767d9","command":"players/all"}...
[07:14:32.144] INFO WebRTC: Raw message: {"message_id":"e96e2971-8aa5-43e4-a16c-c3fea51767d9","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:14:32.145] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: e96e2971-8aa5-43e4-a16c-c3fea51767d9
[07:14:32.145] INFO WebRTC: No match for message_id=e96e2971-8aa5-43e4-a16c-c3fea51767d9, pending: []
[07:14:32.145] INFO RemoteBridge: WebRTC API → WS: {"message_id":"e96e2971-8aa5-43e4-a16c-c3fea51767d9","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:14:32.149] INFO Received message: (message_id, result, partial)
[07:14:32.150] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:32.150] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:14:32.150] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:32.152] INFO RemoteBridge: API WS → WebRTC: {"message_id":"83909811-373c-4695-a380-847c59300197","command":"player_queues/get","args":{"queue_id...
[07:14:32.152] INFO WebRTC: Sending raw: {"message_id":"83909811-373c-4695-a380-847c59300197","command":"player_queues/get","args":{"queue_id...
[07:14:32.181] INFO WebRTC: Raw message: {"message_id":"83909811-373c-4695-a380-847c59300197","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:14:32.182] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 83909811-373c-4695-a380-847c59300197
[07:14:32.182] INFO WebRTC: No match for message_id=83909811-373c-4695-a380-847c59300197, pending: []
[07:14:32.182] INFO RemoteBridge: WebRTC API → WS: {"message_id":"83909811-373c-4695-a380-847c59300197","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:14:32.186] INFO Received message: (message_id, result, partial)
[07:14:32.186] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:32.187] INFO RemoteBridge: API WS → WebRTC: {"message_id":"b5973cd9-7b28-4ee7-b03a-a35377fc57c0","command":"player_queues/items","args":{"queue_...
[07:14:32.187] INFO WebRTC: Sending raw: {"message_id":"b5973cd9-7b28-4ee7-b03a-a35377fc57c0","command":"player_queues/items","args":{"queue_...
[07:14:32.260] INFO WebRTC: Raw message: {"message_id":"b5973cd9-7b28-4ee7-b03a-a35377fc57c0","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:32.266] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: b5973cd9-7b28-4ee7-b03a-a35377fc57c0
[07:14:32.266] INFO WebRTC: No match for message_id=b5973cd9-7b28-4ee7-b03a-a35377fc57c0, pending: []
[07:14:32.266] INFO RemoteBridge: WebRTC API → WS: {"message_id":"b5973cd9-7b28-4ee7-b03a-a35377fc57c0","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:32.276] INFO Received message: (message_id, result, partial)
[07:14:32.278] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:32.324] INFO 💾 Persisted 22 queue items to database
[07:14:32.451] INFO Sendspin: Sending message: client/state
[07:14:32.454] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:14:35.034] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:57 recv:60, Sendspin sent:6 recv:3, lastRecv:2s ago, state:RemoteBridgeState.connected
[07:14:37.097] INFO RemoteBridge: API WS → WebRTC: {"message_id":"69198a66-63f4-4f97-9fe6-fc0841b15077","command":"players/all"}...
[07:14:37.098] INFO WebRTC: Sending raw: {"message_id":"69198a66-63f4-4f97-9fe6-fc0841b15077","command":"players/all"}...
[07:14:37.152] INFO WebRTC: Raw message: {"message_id":"69198a66-63f4-4f97-9fe6-fc0841b15077","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:14:37.152] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 69198a66-63f4-4f97-9fe6-fc0841b15077
[07:14:37.152] INFO WebRTC: No match for message_id=69198a66-63f4-4f97-9fe6-fc0841b15077, pending: []
[07:14:37.152] INFO RemoteBridge: WebRTC API → WS: {"message_id":"69198a66-63f4-4f97-9fe6-fc0841b15077","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:14:37.156] INFO Received message: (message_id, result, partial)
[07:14:37.156] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:37.156] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:14:37.156] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:37.157] INFO RemoteBridge: API WS → WebRTC: {"message_id":"b267ed2f-19ff-46ff-aa7a-9458ee2a3cf4","command":"player_queues/get","args":{"queue_id...
[07:14:37.158] INFO WebRTC: Sending raw: {"message_id":"b267ed2f-19ff-46ff-aa7a-9458ee2a3cf4","command":"player_queues/get","args":{"queue_id...
[07:14:37.188] INFO WebRTC: Raw message: {"message_id":"b267ed2f-19ff-46ff-aa7a-9458ee2a3cf4","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:14:37.188] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: b267ed2f-19ff-46ff-aa7a-9458ee2a3cf4
[07:14:37.188] INFO WebRTC: No match for message_id=b267ed2f-19ff-46ff-aa7a-9458ee2a3cf4, pending: []
[07:14:37.188] INFO RemoteBridge: WebRTC API → WS: {"message_id":"b267ed2f-19ff-46ff-aa7a-9458ee2a3cf4","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:14:37.193] INFO Received message: (message_id, result, partial)
[07:14:37.193] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:37.194] INFO RemoteBridge: API WS → WebRTC: {"message_id":"ca943add-9a15-4109-ac52-79c300784fe9","command":"player_queues/items","args":{"queue_...
[07:14:37.194] INFO WebRTC: Sending raw: {"message_id":"ca943add-9a15-4109-ac52-79c300784fe9","command":"player_queues/items","args":{"queue_...
[07:14:37.262] INFO WebRTC: Raw message: {"message_id":"ca943add-9a15-4109-ac52-79c300784fe9","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:37.263] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: ca943add-9a15-4109-ac52-79c300784fe9
[07:14:37.263] INFO WebRTC: No match for message_id=ca943add-9a15-4109-ac52-79c300784fe9, pending: []
[07:14:37.263] INFO RemoteBridge: WebRTC API → WS: {"message_id":"ca943add-9a15-4109-ac52-79c300784fe9","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:37.266] INFO Received message: (message_id, result, partial)
[07:14:37.267] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:37.321] INFO 💾 Persisted 22 queue items to database
[07:14:37.454] INFO Sendspin: Sending message: client/state
[07:14:37.456] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:14:38.866] INFO Signaling: Sending ping
[07:14:38.881] INFO Signaling: Received message type: ping
[07:14:38.881] INFO Signaling: Received ping from server, sending pong
[07:14:38.881] INFO Signaling: Sending pong
[07:14:38.943] INFO Signaling: Received message type: pong
[07:14:42.096] INFO RemoteBridge: API WS → WebRTC: {"message_id":"2432401b-0f94-40d1-b0ba-377a47477292","command":"players/all"}...
[07:14:42.097] INFO WebRTC: Sending raw: {"message_id":"2432401b-0f94-40d1-b0ba-377a47477292","command":"players/all"}...
[07:14:42.133] INFO WebRTC: Raw message: {"message_id":"2432401b-0f94-40d1-b0ba-377a47477292","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:14:42.135] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 2432401b-0f94-40d1-b0ba-377a47477292
[07:14:42.135] INFO WebRTC: No match for message_id=2432401b-0f94-40d1-b0ba-377a47477292, pending: []
[07:14:42.135] INFO RemoteBridge: WebRTC API → WS: {"message_id":"2432401b-0f94-40d1-b0ba-377a47477292","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:14:42.142] INFO Received message: (message_id, result, partial)
[07:14:42.142] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:42.143] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:14:42.143] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:42.145] INFO RemoteBridge: API WS → WebRTC: {"message_id":"a194a982-40ab-4413-bca7-8d5acafd3bf5","command":"player_queues/get","args":{"queue_id...
[07:14:42.145] INFO WebRTC: Sending raw: {"message_id":"a194a982-40ab-4413-bca7-8d5acafd3bf5","command":"player_queues/get","args":{"queue_id...
[07:14:42.174] INFO WebRTC: Raw message: {"message_id":"a194a982-40ab-4413-bca7-8d5acafd3bf5","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:14:42.175] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: a194a982-40ab-4413-bca7-8d5acafd3bf5
[07:14:42.175] INFO WebRTC: No match for message_id=a194a982-40ab-4413-bca7-8d5acafd3bf5, pending: []
[07:14:42.175] INFO RemoteBridge: WebRTC API → WS: {"message_id":"a194a982-40ab-4413-bca7-8d5acafd3bf5","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:14:42.179] INFO Received message: (message_id, result, partial)
[07:14:42.179] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:42.182] INFO RemoteBridge: API WS → WebRTC: {"message_id":"47263d73-9ad1-4ccd-a3a1-405dad352d17","command":"player_queues/items","args":{"queue_...
[07:14:42.182] INFO WebRTC: Sending raw: {"message_id":"47263d73-9ad1-4ccd-a3a1-405dad352d17","command":"player_queues/items","args":{"queue_...
[07:14:42.261] INFO WebRTC: Raw message: {"message_id":"47263d73-9ad1-4ccd-a3a1-405dad352d17","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:42.262] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 47263d73-9ad1-4ccd-a3a1-405dad352d17
[07:14:42.262] INFO WebRTC: No match for message_id=47263d73-9ad1-4ccd-a3a1-405dad352d17, pending: []
[07:14:42.262] INFO RemoteBridge: WebRTC API → WS: {"message_id":"47263d73-9ad1-4ccd-a3a1-405dad352d17","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:42.273] INFO Received message: (message_id, result, partial)
[07:14:42.276] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:42.331] INFO 💾 Persisted 22 queue items to database
[07:14:42.451] INFO Sendspin: Sending message: client/state
[07:14:42.453] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:14:45.034] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:63 recv:66, Sendspin sent:8 recv:3, lastRecv:2s ago, state:RemoteBridgeState.connected
[07:14:45.043] INFO RemoteBridge: API WS → WebRTC: {"message_id":"9394bebd-6eb9-442c-b579-45df8f26b8c6","command":"info"}...
[07:14:45.043] INFO WebRTC: Sending raw: {"message_id":"9394bebd-6eb9-442c-b579-45df8f26b8c6","command":"info"}...
[07:14:45.063] INFO WebRTC: Raw message: {"message_id":"9394bebd-6eb9-442c-b579-45df8f26b8c6","result":{"server_id":"6626e2bac63c48bfbfaadafdc3a7e32f","server_version":"2.8.0b7","schema_version":28,"min_supported_schema_version":28,"base_url...
[07:14:45.064] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 9394bebd-6eb9-442c-b579-45df8f26b8c6
[07:14:45.064] INFO WebRTC: No match for message_id=9394bebd-6eb9-442c-b579-45df8f26b8c6, pending: []
[07:14:45.064] INFO RemoteBridge: WebRTC API → WS: {"message_id":"9394bebd-6eb9-442c-b579-45df8f26b8c6","result":{"server_id":"6626e2bac63c48bfbfaadafd...
[07:14:45.066] INFO Received message: (message_id, result, partial)
[07:14:45.150] INFO RemoteBridge: API WS → WebRTC: {"message_id":"bf49e26a-06fd-4bab-bd35-e7ddcbb38c9f","command":"music/tracks/library_items","args":{...
[07:14:45.151] INFO WebRTC: Sending raw: {"message_id":"bf49e26a-06fd-4bab-bd35-e7ddcbb38c9f","command":"music/tracks/library_items","args":{...
[07:14:45.181] INFO WebRTC: Raw message: {"message_id":"bf49e26a-06fd-4bab-bd35-e7ddcbb38c9f","error_code":999,"details":"no such column: artists.search_name"}...
[07:14:45.181] INFO WebRTC: Parsed message keys: [message_id, error_code, details], message_id: bf49e26a-06fd-4bab-bd35-e7ddcbb38c9f
[07:14:45.181] INFO WebRTC: No match for message_id=bf49e26a-06fd-4bab-bd35-e7ddcbb38c9f, pending: []
[07:14:45.181] INFO RemoteBridge: WebRTC API → WS: {"message_id":"bf49e26a-06fd-4bab-bd35-e7ddcbb38c9f","error_code":999,"details":"no such column: art...
[07:14:45.183] INFO Received message: (message_id, error_code, details)
[07:14:45.183] INFO Command error: 999 - no such column: artists.search_name
[07:14:45.183] INFO Error getting tracks: Exception: 999: no such column: artists.search_name
[07:14:46.327] INFO RemoteBridge: API WS → WebRTC: {"message_id":"bb333f37-2f71-4831-bc38-4e05146fa1b8","command":"music/tracks/library_items","args":{...
[07:14:46.327] INFO WebRTC: Sending raw: {"message_id":"bb333f37-2f71-4831-bc38-4e05146fa1b8","command":"music/tracks/library_items","args":{...
[07:14:46.360] INFO WebRTC: Raw message: {"message_id":"bb333f37-2f71-4831-bc38-4e05146fa1b8","error_code":999,"details":"no such column: artists.search_name"}...
[07:14:46.361] INFO WebRTC: Parsed message keys: [message_id, error_code, details], message_id: bb333f37-2f71-4831-bc38-4e05146fa1b8
[07:14:46.361] INFO WebRTC: No match for message_id=bb333f37-2f71-4831-bc38-4e05146fa1b8, pending: []
[07:14:46.361] INFO RemoteBridge: WebRTC API → WS: {"message_id":"bb333f37-2f71-4831-bc38-4e05146fa1b8","error_code":999,"details":"no such column: art...
[07:14:46.362] INFO Received message: (message_id, error_code, details)
[07:14:46.362] INFO Command error: 999 - no such column: artists.search_name
[07:14:46.362] INFO Error getting tracks: Exception: 999: no such column: artists.search_name
[07:14:47.095] INFO RemoteBridge: API WS → WebRTC: {"message_id":"45749a18-559d-46ec-bbdc-282055d625cd","command":"players/all"}...
[07:14:47.095] INFO WebRTC: Sending raw: {"message_id":"45749a18-559d-46ec-bbdc-282055d625cd","command":"players/all"}...
[07:14:47.134] INFO WebRTC: Raw message: {"message_id":"45749a18-559d-46ec-bbdc-282055d625cd","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:14:47.135] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 45749a18-559d-46ec-bbdc-282055d625cd
[07:14:47.135] INFO WebRTC: No match for message_id=45749a18-559d-46ec-bbdc-282055d625cd, pending: []
[07:14:47.135] INFO RemoteBridge: WebRTC API → WS: {"message_id":"45749a18-559d-46ec-bbdc-282055d625cd","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:14:47.139] INFO Received message: (message_id, result, partial)
[07:14:47.140] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:47.140] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:14:47.140] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:47.142] INFO RemoteBridge: API WS → WebRTC: {"message_id":"92717afe-05a4-4550-a4fc-505eb87a0f19","command":"player_queues/get","args":{"queue_id...
[07:14:47.142] INFO WebRTC: Sending raw: {"message_id":"92717afe-05a4-4550-a4fc-505eb87a0f19","command":"player_queues/get","args":{"queue_id...
[07:14:47.168] INFO WebRTC: Raw message: {"message_id":"92717afe-05a4-4550-a4fc-505eb87a0f19","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:14:47.169] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 92717afe-05a4-4550-a4fc-505eb87a0f19
[07:14:47.169] INFO WebRTC: No match for message_id=92717afe-05a4-4550-a4fc-505eb87a0f19, pending: []
[07:14:47.169] INFO RemoteBridge: WebRTC API → WS: {"message_id":"92717afe-05a4-4550-a4fc-505eb87a0f19","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:14:47.173] INFO Received message: (message_id, result, partial)
[07:14:47.174] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:47.176] INFO RemoteBridge: API WS → WebRTC: {"message_id":"8a319670-9079-4bdc-9526-2a74cebf795a","command":"player_queues/items","args":{"queue_...
[07:14:47.176] INFO WebRTC: Sending raw: {"message_id":"8a319670-9079-4bdc-9526-2a74cebf795a","command":"player_queues/items","args":{"queue_...
[07:14:47.243] INFO WebRTC: Raw message: {"message_id":"8a319670-9079-4bdc-9526-2a74cebf795a","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:47.248] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 8a319670-9079-4bdc-9526-2a74cebf795a
[07:14:47.248] INFO WebRTC: No match for message_id=8a319670-9079-4bdc-9526-2a74cebf795a, pending: []
[07:14:47.248] INFO RemoteBridge: WebRTC API → WS: {"message_id":"8a319670-9079-4bdc-9526-2a74cebf795a","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:47.252] INFO Received message: (message_id, result, partial)
[07:14:47.253] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:47.284] INFO 💾 Persisted 22 queue items to database
[07:14:47.450] INFO Sendspin: Sending message: client/time
[07:14:47.453] INFO Sendspin: Sending message: client/state
[07:14:47.455] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/time","payload":{"client_transmitted":1769238887450625}}...
[07:14:47.455] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:14:47.471] INFO WebRTC: Sendspin text message: {"payload":{"client_transmitted":1769238887450625,"server_received":120429376415,"server_transmitted...
[07:14:47.471] INFO RemoteBridge: WebRTC Sendspin text → WS: {"payload":{"client_transmitted":1769238887450625,"server_received":120429376415,"server_transmitted...
[07:14:47.475] INFO Sendspin: Received message type: server/time
[07:14:47.475] INFO Sendspin: Received server/time response
[07:14:47.906] INFO RemoteBridge: API WS → WebRTC: {"message_id":"045e308c-80af-4a79-b380-d6d27eb8fb23","command":"music/tracks/library_items","args":{...
[07:14:47.907] INFO WebRTC: Sending raw: {"message_id":"045e308c-80af-4a79-b380-d6d27eb8fb23","command":"music/tracks/library_items","args":{...
[07:14:47.941] INFO WebRTC: Raw message: {"message_id":"045e308c-80af-4a79-b380-d6d27eb8fb23","error_code":999,"details":"no such column: artists.search_name"}...
[07:14:47.941] INFO WebRTC: Parsed message keys: [message_id, error_code, details], message_id: 045e308c-80af-4a79-b380-d6d27eb8fb23
[07:14:47.941] INFO WebRTC: No match for message_id=045e308c-80af-4a79-b380-d6d27eb8fb23, pending: []
[07:14:47.941] INFO RemoteBridge: WebRTC API → WS: {"message_id":"045e308c-80af-4a79-b380-d6d27eb8fb23","error_code":999,"details":"no such column: art...
[07:14:47.943] INFO Received message: (message_id, error_code, details)
[07:14:47.943] INFO Command error: 999 - no such column: artists.search_name
[07:14:47.943] INFO Error getting tracks: Exception: 999: no such column: artists.search_name
[07:14:52.096] INFO RemoteBridge: API WS → WebRTC: {"message_id":"fb886f30-2e48-470c-8dd9-5133ff2ea510","command":"players/all"}...
[07:14:52.096] INFO WebRTC: Sending raw: {"message_id":"fb886f30-2e48-470c-8dd9-5133ff2ea510","command":"players/all"}...
[07:14:52.133] INFO WebRTC: Raw message: {"message_id":"fb886f30-2e48-470c-8dd9-5133ff2ea510","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:14:52.135] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: fb886f30-2e48-470c-8dd9-5133ff2ea510
[07:14:52.135] INFO WebRTC: No match for message_id=fb886f30-2e48-470c-8dd9-5133ff2ea510, pending: []
[07:14:52.135] INFO RemoteBridge: WebRTC API → WS: {"message_id":"fb886f30-2e48-470c-8dd9-5133ff2ea510","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:14:52.141] INFO Received message: (message_id, result, partial)
[07:14:52.142] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:52.142] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:14:52.142] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:52.144] INFO RemoteBridge: API WS → WebRTC: {"message_id":"5925fac9-8d18-45c4-9f2d-0e1e2b3a5f40","command":"player_queues/get","args":{"queue_id...
[07:14:52.145] INFO WebRTC: Sending raw: {"message_id":"5925fac9-8d18-45c4-9f2d-0e1e2b3a5f40","command":"player_queues/get","args":{"queue_id...
[07:14:52.174] INFO WebRTC: Raw message: {"message_id":"5925fac9-8d18-45c4-9f2d-0e1e2b3a5f40","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:14:52.174] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 5925fac9-8d18-45c4-9f2d-0e1e2b3a5f40
[07:14:52.175] INFO WebRTC: No match for message_id=5925fac9-8d18-45c4-9f2d-0e1e2b3a5f40, pending: []
[07:14:52.175] INFO RemoteBridge: WebRTC API → WS: {"message_id":"5925fac9-8d18-45c4-9f2d-0e1e2b3a5f40","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:14:52.179] INFO Received message: (message_id, result, partial)
[07:14:52.179] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:52.181] INFO RemoteBridge: API WS → WebRTC: {"message_id":"e6bbd39d-bdcc-4dea-b5d3-98ab9d4b6b9a","command":"player_queues/items","args":{"queue_...
[07:14:52.182] INFO WebRTC: Sending raw: {"message_id":"e6bbd39d-bdcc-4dea-b5d3-98ab9d4b6b9a","command":"player_queues/items","args":{"queue_...
[07:14:52.255] INFO WebRTC: Raw message: {"message_id":"e6bbd39d-bdcc-4dea-b5d3-98ab9d4b6b9a","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:52.256] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: e6bbd39d-bdcc-4dea-b5d3-98ab9d4b6b9a
[07:14:52.256] INFO WebRTC: No match for message_id=e6bbd39d-bdcc-4dea-b5d3-98ab9d4b6b9a, pending: []
[07:14:52.256] INFO RemoteBridge: WebRTC API → WS: {"message_id":"e6bbd39d-bdcc-4dea-b5d3-98ab9d4b6b9a","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:52.266] INFO Received message: (message_id, result, partial)
[07:14:52.268] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:52.300] INFO 💾 Persisted 22 queue items to database
[07:14:52.453] INFO Sendspin: Sending message: client/state
[07:14:52.455] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:14:53.866] INFO Signaling: Sending ping
[07:14:53.900] INFO Signaling: Received message type: pong
[07:14:55.034] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:73 recv:76, Sendspin sent:11 recv:4, lastRecv:2s ago, state:RemoteBridgeState.connected
[07:14:57.093] INFO RemoteBridge: API WS → WebRTC: {"message_id":"2c79ee2d-3f46-485f-8245-a61d0a99f399","command":"players/all"}...
[07:14:57.093] INFO WebRTC: Sending raw: {"message_id":"2c79ee2d-3f46-485f-8245-a61d0a99f399","command":"players/all"}...
[07:14:57.126] INFO WebRTC: Raw message: {"message_id":"2c79ee2d-3f46-485f-8245-a61d0a99f399","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:14:57.127] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 2c79ee2d-3f46-485f-8245-a61d0a99f399
[07:14:57.127] INFO WebRTC: No match for message_id=2c79ee2d-3f46-485f-8245-a61d0a99f399, pending: []
[07:14:57.127] INFO RemoteBridge: WebRTC API → WS: {"message_id":"2c79ee2d-3f46-485f-8245-a61d0a99f399","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:14:57.129] INFO Received message: (message_id, result, partial)
[07:14:57.129] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:14:57.129] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:14:57.129] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:14:57.130] INFO RemoteBridge: API WS → WebRTC: {"message_id":"fcc86bb2-e5b5-4732-9c7e-1c87a96f5ca5","command":"player_queues/get","args":{"queue_id...
[07:14:57.130] INFO WebRTC: Sending raw: {"message_id":"fcc86bb2-e5b5-4732-9c7e-1c87a96f5ca5","command":"player_queues/get","args":{"queue_id...
[07:14:57.154] INFO WebRTC: Raw message: {"message_id":"fcc86bb2-e5b5-4732-9c7e-1c87a96f5ca5","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:14:57.155] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: fcc86bb2-e5b5-4732-9c7e-1c87a96f5ca5
[07:14:57.155] INFO WebRTC: No match for message_id=fcc86bb2-e5b5-4732-9c7e-1c87a96f5ca5, pending: []
[07:14:57.155] INFO RemoteBridge: WebRTC API → WS: {"message_id":"fcc86bb2-e5b5-4732-9c7e-1c87a96f5ca5","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:14:57.156] INFO Received message: (message_id, result, partial)
[07:14:57.156] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:14:57.158] INFO RemoteBridge: API WS → WebRTC: {"message_id":"39d378b5-7f46-4c1e-a24c-fa41360ed5ff","command":"player_queues/items","args":{"queue_...
[07:14:57.158] INFO WebRTC: Sending raw: {"message_id":"39d378b5-7f46-4c1e-a24c-fa41360ed5ff","command":"player_queues/items","args":{"queue_...
[07:14:57.217] INFO WebRTC: Raw message: {"message_id":"39d378b5-7f46-4c1e-a24c-fa41360ed5ff","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:14:57.219] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 39d378b5-7f46-4c1e-a24c-fa41360ed5ff
[07:14:57.219] INFO WebRTC: No match for message_id=39d378b5-7f46-4c1e-a24c-fa41360ed5ff, pending: []
[07:14:57.219] INFO RemoteBridge: WebRTC API → WS: {"message_id":"39d378b5-7f46-4c1e-a24c-fa41360ed5ff","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:14:57.229] INFO Received message: (message_id, result, partial)
[07:14:57.230] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:14:57.254] INFO 💾 Persisted 22 queue items to database
[07:14:57.459] INFO Sendspin: Sending message: client/state
[07:14:57.462] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:02.104] INFO RemoteBridge: API WS → WebRTC: {"message_id":"eff4eb4c-f087-4605-beef-5673e87aaee5","command":"players/all"}...
[07:15:02.105] INFO WebRTC: Sending raw: {"message_id":"eff4eb4c-f087-4605-beef-5673e87aaee5","command":"players/all"}...
[07:15:02.136] INFO WebRTC: Raw message: {"message_id":"eff4eb4c-f087-4605-beef-5673e87aaee5","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:15:02.136] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: eff4eb4c-f087-4605-beef-5673e87aaee5
[07:15:02.136] INFO WebRTC: No match for message_id=eff4eb4c-f087-4605-beef-5673e87aaee5, pending: []
[07:15:02.136] INFO RemoteBridge: WebRTC API → WS: {"message_id":"eff4eb4c-f087-4605-beef-5673e87aaee5","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:15:02.138] INFO Received message: (message_id, result, partial)
[07:15:02.138] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:15:02.138] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:15:02.138] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:15:02.140] INFO RemoteBridge: API WS → WebRTC: {"message_id":"2649a641-2369-4326-bb00-2543159a7a32","command":"player_queues/get","args":{"queue_id...
[07:15:02.140] INFO WebRTC: Sending raw: {"message_id":"2649a641-2369-4326-bb00-2543159a7a32","command":"player_queues/get","args":{"queue_id...
[07:15:02.161] INFO WebRTC: Raw message: {"message_id":"2649a641-2369-4326-bb00-2543159a7a32","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:15:02.161] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 2649a641-2369-4326-bb00-2543159a7a32
[07:15:02.161] INFO WebRTC: No match for message_id=2649a641-2369-4326-bb00-2543159a7a32, pending: []
[07:15:02.161] INFO RemoteBridge: WebRTC API → WS: {"message_id":"2649a641-2369-4326-bb00-2543159a7a32","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:15:02.163] INFO Received message: (message_id, result, partial)
[07:15:02.163] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:15:02.164] INFO RemoteBridge: API WS → WebRTC: {"message_id":"678fd17c-c932-4a84-b053-35c0ce42951a","command":"player_queues/items","args":{"queue_...
[07:15:02.164] INFO WebRTC: Sending raw: {"message_id":"678fd17c-c932-4a84-b053-35c0ce42951a","command":"player_queues/items","args":{"queue_...
[07:15:02.244] INFO WebRTC: Raw message: {"message_id":"678fd17c-c932-4a84-b053-35c0ce42951a","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:15:02.245] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 678fd17c-c932-4a84-b053-35c0ce42951a
[07:15:02.245] INFO WebRTC: No match for message_id=678fd17c-c932-4a84-b053-35c0ce42951a, pending: []
[07:15:02.245] INFO RemoteBridge: WebRTC API → WS: {"message_id":"678fd17c-c932-4a84-b053-35c0ce42951a","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:15:02.255] INFO Received message: (message_id, result, partial)
[07:15:02.256] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:15:02.345] INFO 💾 Persisted 22 queue items to database
[07:15:02.450] INFO Sendspin: Sending message: client/state
[07:15:02.451] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:05.033] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:79 recv:82, Sendspin sent:13 recv:4, lastRecv:2s ago, state:RemoteBridgeState.connected
[07:15:07.100] INFO RemoteBridge: API WS → WebRTC: {"message_id":"d0c9d949-cda8-4522-a735-49e6d0435362","command":"players/all"}...
[07:15:07.101] INFO WebRTC: Sending raw: {"message_id":"d0c9d949-cda8-4522-a735-49e6d0435362","command":"players/all"}...
[07:15:07.140] INFO WebRTC: Raw message: {"message_id":"d0c9d949-cda8-4522-a735-49e6d0435362","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:15:07.142] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: d0c9d949-cda8-4522-a735-49e6d0435362
[07:15:07.142] INFO WebRTC: No match for message_id=d0c9d949-cda8-4522-a735-49e6d0435362, pending: []
[07:15:07.142] INFO RemoteBridge: WebRTC API → WS: {"message_id":"d0c9d949-cda8-4522-a735-49e6d0435362","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:15:07.149] INFO Received message: (message_id, result, partial)
[07:15:07.150] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:15:07.150] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:15:07.150] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:15:07.152] INFO RemoteBridge: API WS → WebRTC: {"message_id":"12592cb4-4332-4a30-8438-654dab0db6f0","command":"player_queues/get","args":{"queue_id...
[07:15:07.152] INFO WebRTC: Sending raw: {"message_id":"12592cb4-4332-4a30-8438-654dab0db6f0","command":"player_queues/get","args":{"queue_id...
[07:15:07.182] INFO WebRTC: Raw message: {"message_id":"12592cb4-4332-4a30-8438-654dab0db6f0","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:15:07.183] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 12592cb4-4332-4a30-8438-654dab0db6f0
[07:15:07.183] INFO WebRTC: No match for message_id=12592cb4-4332-4a30-8438-654dab0db6f0, pending: []
[07:15:07.183] INFO RemoteBridge: WebRTC API → WS: {"message_id":"12592cb4-4332-4a30-8438-654dab0db6f0","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:15:07.186] INFO Received message: (message_id, result, partial)
[07:15:07.186] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:15:07.189] INFO RemoteBridge: API WS → WebRTC: {"message_id":"fa6282de-c340-4ad4-8264-0168cf1b1ef9","command":"player_queues/items","args":{"queue_...
[07:15:07.189] INFO WebRTC: Sending raw: {"message_id":"fa6282de-c340-4ad4-8264-0168cf1b1ef9","command":"player_queues/items","args":{"queue_...
[07:15:07.258] INFO WebRTC: Raw message: {"message_id":"fa6282de-c340-4ad4-8264-0168cf1b1ef9","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:15:07.264] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: fa6282de-c340-4ad4-8264-0168cf1b1ef9
[07:15:07.264] INFO WebRTC: No match for message_id=fa6282de-c340-4ad4-8264-0168cf1b1ef9, pending: []
[07:15:07.264] INFO RemoteBridge: WebRTC API → WS: {"message_id":"fa6282de-c340-4ad4-8264-0168cf1b1ef9","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:15:07.270] INFO Received message: (message_id, result, partial)
[07:15:07.271] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:15:07.361] INFO 💾 Persisted 22 queue items to database
[07:15:07.450] INFO Sendspin: Sending message: client/state
[07:15:07.452] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:08.867] INFO Signaling: Sending ping
[07:15:08.880] INFO Signaling: Received message type: ping
[07:15:08.881] INFO Signaling: Received ping from server, sending pong
[07:15:08.881] INFO Signaling: Sending pong
[07:15:08.932] INFO Signaling: Received message type: pong
[07:15:12.098] INFO RemoteBridge: API WS → WebRTC: {"message_id":"69850bdc-b3a1-4983-abbe-93aa6addd7a3","command":"players/all"}...
[07:15:12.099] INFO WebRTC: Sending raw: {"message_id":"69850bdc-b3a1-4983-abbe-93aa6addd7a3","command":"players/all"}...
[07:15:12.136] INFO WebRTC: Raw message: {"message_id":"69850bdc-b3a1-4983-abbe-93aa6addd7a3","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:15:12.137] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 69850bdc-b3a1-4983-abbe-93aa6addd7a3
[07:15:12.137] INFO WebRTC: No match for message_id=69850bdc-b3a1-4983-abbe-93aa6addd7a3, pending: []
[07:15:12.138] INFO RemoteBridge: WebRTC API → WS: {"message_id":"69850bdc-b3a1-4983-abbe-93aa6addd7a3","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:15:12.145] INFO Received message: (message_id, result, partial)
[07:15:12.146] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:15:12.146] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:15:12.147] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:15:12.149] INFO RemoteBridge: API WS → WebRTC: {"message_id":"2901a030-f803-443e-9ec9-d72452f4336d","command":"player_queues/get","args":{"queue_id...
[07:15:12.149] INFO WebRTC: Sending raw: {"message_id":"2901a030-f803-443e-9ec9-d72452f4336d","command":"player_queues/get","args":{"queue_id...
[07:15:12.178] INFO WebRTC: Raw message: {"message_id":"2901a030-f803-443e-9ec9-d72452f4336d","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:15:12.179] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 2901a030-f803-443e-9ec9-d72452f4336d
[07:15:12.179] INFO WebRTC: No match for message_id=2901a030-f803-443e-9ec9-d72452f4336d, pending: []
[07:15:12.180] INFO RemoteBridge: WebRTC API → WS: {"message_id":"2901a030-f803-443e-9ec9-d72452f4336d","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:15:12.183] INFO Received message: (message_id, result, partial)
[07:15:12.183] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:15:12.185] INFO RemoteBridge: API WS → WebRTC: {"message_id":"70e25e48-f181-4839-bd38-2a5a3869ce97","command":"player_queues/items","args":{"queue_...
[07:15:12.185] INFO WebRTC: Sending raw: {"message_id":"70e25e48-f181-4839-bd38-2a5a3869ce97","command":"player_queues/items","args":{"queue_...
[07:15:12.262] INFO WebRTC: Raw message: {"message_id":"70e25e48-f181-4839-bd38-2a5a3869ce97","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:15:12.266] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 70e25e48-f181-4839-bd38-2a5a3869ce97
[07:15:12.266] INFO WebRTC: No match for message_id=70e25e48-f181-4839-bd38-2a5a3869ce97, pending: []
[07:15:12.267] INFO RemoteBridge: WebRTC API → WS: {"message_id":"70e25e48-f181-4839-bd38-2a5a3869ce97","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:15:12.274] INFO Received message: (message_id, result, partial)
[07:15:12.277] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:15:12.324] INFO 💾 Persisted 22 queue items to database
[07:15:12.451] INFO Sendspin: Sending message: client/state
[07:15:12.453] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:15.033] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:85 recv:88, Sendspin sent:15 recv:4, lastRecv:2s ago, state:RemoteBridgeState.connected
[07:15:15.044] INFO RemoteBridge: API WS → WebRTC: {"message_id":"d2528ca6-bd60-4ad4-b61b-ceac5db46b3d","command":"info"}...
[07:15:15.044] INFO WebRTC: Sending raw: {"message_id":"d2528ca6-bd60-4ad4-b61b-ceac5db46b3d","command":"info"}...
[07:15:15.065] INFO WebRTC: Raw message: {"message_id":"d2528ca6-bd60-4ad4-b61b-ceac5db46b3d","result":{"server_id":"6626e2bac63c48bfbfaadafdc3a7e32f","server_version":"2.8.0b7","schema_version":28,"min_supported_schema_version":28,"base_url...
[07:15:15.065] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: d2528ca6-bd60-4ad4-b61b-ceac5db46b3d
[07:15:15.065] INFO WebRTC: No match for message_id=d2528ca6-bd60-4ad4-b61b-ceac5db46b3d, pending: []
[07:15:15.066] INFO RemoteBridge: WebRTC API → WS: {"message_id":"d2528ca6-bd60-4ad4-b61b-ceac5db46b3d","result":{"server_id":"6626e2bac63c48bfbfaadafd...
[07:15:15.069] INFO Received message: (message_id, result, partial)
[07:15:17.094] INFO RemoteBridge: API WS → WebRTC: {"message_id":"64d08a18-dca0-4aca-88a3-ab7e58c9ae8b","command":"players/all"}...
[07:15:17.094] INFO WebRTC: Sending raw: {"message_id":"64d08a18-dca0-4aca-88a3-ab7e58c9ae8b","command":"players/all"}...
[07:15:17.121] INFO WebRTC: Raw message: {"message_id":"64d08a18-dca0-4aca-88a3-ab7e58c9ae8b","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:15:17.121] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 64d08a18-dca0-4aca-88a3-ab7e58c9ae8b
[07:15:17.121] INFO WebRTC: No match for message_id=64d08a18-dca0-4aca-88a3-ab7e58c9ae8b, pending: []
[07:15:17.121] INFO RemoteBridge: WebRTC API → WS: {"message_id":"64d08a18-dca0-4aca-88a3-ab7e58c9ae8b","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:15:17.124] INFO Received message: (message_id, result, partial)
[07:15:17.125] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:15:17.125] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:15:17.125] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:15:17.126] INFO RemoteBridge: API WS → WebRTC: {"message_id":"41b26ddf-4868-42eb-b099-963dfe4b4bb7","command":"player_queues/get","args":{"queue_id...
[07:15:17.126] INFO WebRTC: Sending raw: {"message_id":"41b26ddf-4868-42eb-b099-963dfe4b4bb7","command":"player_queues/get","args":{"queue_id...
[07:15:17.151] INFO WebRTC: Raw message: {"message_id":"41b26ddf-4868-42eb-b099-963dfe4b4bb7","result":{"queue_id":"ensemble_Chris_CP11.251","active":true,"display_name":"Chris' Phone","available":true,"items":22,"shuffle_enabled":false,"rep...
[07:15:17.151] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 41b26ddf-4868-42eb-b099-963dfe4b4bb7
[07:15:17.152] INFO WebRTC: No match for message_id=41b26ddf-4868-42eb-b099-963dfe4b4bb7, pending: []
[07:15:17.152] INFO RemoteBridge: WebRTC API → WS: {"message_id":"41b26ddf-4868-42eb-b099-963dfe4b4bb7","result":{"queue_id":"ensemble_Chris_CP11.251",...
[07:15:17.154] INFO Received message: (message_id, result, partial)
[07:15:17.154] INFO 🔀 Queue metadata: shuffle_enabled=false, repeat_mode=off
[07:15:17.155] INFO RemoteBridge: API WS → WebRTC: {"message_id":"4d4a8a66-1501-45bd-a9db-b05e6f84929a","command":"player_queues/items","args":{"queue_...
[07:15:17.155] INFO WebRTC: Sending raw: {"message_id":"4d4a8a66-1501-45bd-a9db-b05e6f84929a","command":"player_queues/items","args":{"queue_...
[07:15:17.228] INFO WebRTC: Raw message: {"message_id":"4d4a8a66-1501-45bd-a9db-b05e6f84929a","result":[{"queue_id":"ensemble_Chris_CP11.251","queue_item_id":"616d0e4bb5024d6a8d95d4c39133d018","name":"Cat Stevens - Matthew & Son","duration":...
[07:15:17.233] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 4d4a8a66-1501-45bd-a9db-b05e6f84929a
[07:15:17.233] INFO WebRTC: No match for message_id=4d4a8a66-1501-45bd-a9db-b05e6f84929a, pending: []
[07:15:17.233] INFO RemoteBridge: WebRTC API → WS: {"message_id":"4d4a8a66-1501-45bd-a9db-b05e6f84929a","result":[{"queue_id":"ensemble_Chris_CP11.251"...
[07:15:17.236] INFO Received message: (message_id, result, partial)
[07:15:17.237] INFO 🔔 Starting notification timer for Sendspin PCM player
[07:15:17.278] INFO 💾 Persisted 22 queue items to database
[07:15:17.450] INFO Sendspin: Sending message: client/time
[07:15:17.452] INFO Sendspin: Sending message: client/state
[07:15:17.453] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/time","payload":{"client_transmitted":1769238917450549}}...
[07:15:17.454] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:17.475] INFO WebRTC: Sendspin text message: {"payload":{"client_transmitted":1769238917450549,"server_received":120459376466,"server_transmitted...
[07:15:17.475] INFO RemoteBridge: WebRTC Sendspin text → WS: {"payload":{"client_transmitted":1769238917450549,"server_received":120459376466,"server_transmitted...
[07:15:17.477] INFO Sendspin: Received message type: server/time
[07:15:17.477] INFO Sendspin: Received server/time response
[07:15:18.244] INFO 🔄 Forcing full library sync with providers: ALL
[07:15:18.244] INFO 🔄 Starting background library sync...
[07:15:18.244] INFO 🎨 Sync using albumArtistsOnly: true
[07:15:18.266] INFO 📡 Fetching from all providers (no source tracking, limit: 100)
[07:15:18.267] INFO 🌐 Remote mode: using sequential requests with delays
[07:15:18.267] INFO 🔍 API getAlbums args: {limit: 100}
[07:15:18.268] INFO RemoteBridge: API WS → WebRTC: {"message_id":"2cafd070-36d3-40a3-a067-fd93b493854b","command":"music/albums/library_items","args":{...
[07:15:18.268] INFO WebRTC: Sending raw: {"message_id":"2cafd070-36d3-40a3-a067-fd93b493854b","command":"music/albums/library_items","args":{...
[07:15:18.516] INFO WebRTC: Raw message: {"message_id":"2cafd070-36d3-40a3-a067-fd93b493854b","result":[{"item_id":"87","provider":"library","name":"★","version":"","sort_name":"★","uri":"library://album/87","external_ids":[["musicbrainz_alb...
[07:15:18.519] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 2cafd070-36d3-40a3-a067-fd93b493854b
[07:15:18.520] INFO WebRTC: No match for message_id=2cafd070-36d3-40a3-a067-fd93b493854b, pending: []
[07:15:18.520] INFO RemoteBridge: WebRTC API → WS: {"message_id":"2cafd070-36d3-40a3-a067-fd93b493854b","result":[{"item_id":"87","provider":"library",...
[07:15:18.533] INFO Received message: (message_id, result, partial)
[07:15:18.533] INFO 🔍 API getAlbums returned 100 items
[07:15:18.533] INFO 📥 Got 100 albums
[07:15:19.035] INFO 🔍 API getArtists args: {limit: 100, album_artists_only: true}
[07:15:19.038] INFO RemoteBridge: API WS → WebRTC: {"message_id":"01f4ed9e-2094-454e-97ac-6082664bc5e4","command":"music/artists/library_items","args":...
[07:15:19.038] INFO WebRTC: Sending raw: {"message_id":"01f4ed9e-2094-454e-97ac-6082664bc5e4","command":"music/artists/library_items","args":...
[07:15:19.377] INFO WebRTC: Raw message: {"message_id":"01f4ed9e-2094-454e-97ac-6082664bc5e4","result":[{"item_id":"43","provider":"library","name":"AC/DC","version":"","sort_name":"ac/dc","uri":"library://artist/43","external_ids":[["musicb...
[07:15:19.382] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 01f4ed9e-2094-454e-97ac-6082664bc5e4
[07:15:19.383] INFO WebRTC: No match for message_id=01f4ed9e-2094-454e-97ac-6082664bc5e4, pending: []
[07:15:19.383] INFO RemoteBridge: WebRTC API → WS: {"message_id":"01f4ed9e-2094-454e-97ac-6082664bc5e4","result":[{"item_id":"43","provider":"library",...
[07:15:19.403] INFO Received message: (message_id, result, partial)
[07:15:19.403] INFO 🔍 API getArtists returned 77 items
[07:15:19.403] INFO 📥 Got 77 artists
[07:15:19.905] INFO 📚 getAudiobooks: absProviderIds={}, providerInstanceIds=null
[07:15:19.909] INFO RemoteBridge: API WS → WebRTC: {"message_id":"040cd6d0-c831-43d9-b1ed-6a64d3b33b3b","command":"music/audiobooks/library_items","arg...
[07:15:19.909] INFO WebRTC: Sending raw: {"message_id":"040cd6d0-c831-43d9-b1ed-6a64d3b33b3b","command":"music/audiobooks/library_items","arg...
[07:15:20.223] INFO WebRTC: Raw message: {"message_id":"040cd6d0-c831-43d9-b1ed-6a64d3b33b3b","result":[{"item_id":"522","provider":"library","name":"1001 Nights","version":"","sort_name":"1001 nights","uri":"library://audiobook/522","extern...
[07:15:20.227] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 040cd6d0-c831-43d9-b1ed-6a64d3b33b3b
[07:15:20.227] INFO WebRTC: No match for message_id=040cd6d0-c831-43d9-b1ed-6a64d3b33b3b, pending: []
[07:15:20.227] INFO RemoteBridge: WebRTC API → WS: {"message_id":"040cd6d0-c831-43d9-b1ed-6a64d3b33b3b","result":[{"item_id":"522","provider":"library"...
[07:15:20.256] INFO Received message: (message_id, result, partial)
[07:15:20.256] INFO 📚 Got 100 audiobooks from library_items API
[07:15:20.256] INFO 📚 Total audiobooks: 100
[07:15:20.256] INFO 📥 Got 100 audiobooks
[07:15:20.764] INFO RemoteBridge: API WS → WebRTC: {"message_id":"3d212d15-0ed0-4ef1-b7ea-1bbe51dc423b","command":"music/playlists/library_items","args...
[07:15:20.764] INFO WebRTC: Sending raw: {"message_id":"3d212d15-0ed0-4ef1-b7ea-1bbe51dc423b","command":"music/playlists/library_items","args...
[07:15:20.820] INFO WebRTC: Raw message: {"message_id":"3d212d15-0ed0-4ef1-b7ea-1bbe51dc423b","result":[{"item_id":"14","provider":"library","name":"00.Info","version":"","sort_name":"00.info","uri":"library://playlist/14","external_ids":[],...
[07:15:20.821] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 3d212d15-0ed0-4ef1-b7ea-1bbe51dc423b
[07:15:20.821] INFO WebRTC: No match for message_id=3d212d15-0ed0-4ef1-b7ea-1bbe51dc423b, pending: []
[07:15:20.821] INFO RemoteBridge: WebRTC API → WS: {"message_id":"3d212d15-0ed0-4ef1-b7ea-1bbe51dc423b","result":[{"item_id":"14","provider":"library",...
[07:15:20.825] INFO Received message: (message_id, result, partial)
[07:15:20.826] INFO 📥 Got 17 playlists
[07:15:20.826] INFO 📥 Total: 100 albums, 77 artists, 100 audiobooks, 17 playlists
[07:15:20.842] INFO 💾 Batch saved 100 albums to cache
[07:15:20.862] INFO 💾 Batch saved 77 artists to cache
[07:15:20.908] INFO 💾 Batch saved 100 audiobooks to cache
[07:15:20.918] INFO 💾 Batch saved 17 playlists to cache
[07:15:20.941] INFO ✅ Library sync complete
[07:15:20.941] INFO 📊 Source tracking: 0 albums, 0 artists, 0 audiobooks have provider info
[07:15:20.942] INFO RemoteBridge: API WS → WebRTC: {"message_id":"6972d4ce-dec5-46b3-8d2b-9bbddbbf6cee","command":"music/tracks/library_items","args":{...
[07:15:20.942] INFO WebRTC: Sending raw: {"message_id":"6972d4ce-dec5-46b3-8d2b-9bbddbbf6cee","command":"music/tracks/library_items","args":{...
[07:15:22.095] INFO RemoteBridge: API WS → WebRTC: {"message_id":"09ea6233-4756-422d-ac90-5d9d998dcbc5","command":"players/all"}...
[07:15:22.096] INFO WebRTC: Sending raw: {"message_id":"09ea6233-4756-422d-ac90-5d9d998dcbc5","command":"players/all"}...
[07:15:22.452] INFO Sendspin: Sending message: client/state
[07:15:22.455] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:23.864] INFO Signaling: Sending ping
[07:15:23.912] INFO Signaling: Received message type: pong
[07:15:24.008] INFO WebRTC: Raw message: {"message_id":"09ea6233-4756-422d-ac90-5d9d998dcbc5","result":[{"player_id":"7ad8d968-e687-ef3c-ba07-f6e5c0a2806d","provider":"chromecast","type":"player","name":"Kitchen speaker","available":true,"de...
[07:15:24.009] INFO WebRTC: Parsed message keys: [message_id, result, partial], message_id: 09ea6233-4756-422d-ac90-5d9d998dcbc5
[07:15:24.010] INFO WebRTC: No match for message_id=09ea6233-4756-422d-ac90-5d9d998dcbc5, pending: []
[07:15:24.010] INFO RemoteBridge: WebRTC API → WS: {"message_id":"09ea6233-4756-422d-ac90-5d9d998dcbc5","result":[{"player_id":"7ad8d968-e687-ef3c-ba07...
[07:15:24.015] INFO Received message: (message_id, result, partial)
[07:15:24.016] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:15:24.016] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:15:24.016] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:15:24.018] INFO RemoteBridge: API WS → WebRTC: {"message_id":"1901508f-d15e-46f6-bc6c-e697ffc289fc","command":"player_queues/get","args":{"queue_id...
[07:15:24.018] INFO WebRTC: Sending raw: {"message_id":"1901508f-d15e-46f6-bc6c-e697ffc289fc","command":"player_queues/get","args":{"queue_id...
[07:15:25.032] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:96 recv:97, Sendspin sent:18 recv:5, lastRecv:1s ago, state:RemoteBridgeState.connected
[07:15:27.097] INFO RemoteBridge: API WS → WebRTC: {"message_id":"fe82ac46-060f-4a0e-8c29-f78e99aaa760","command":"players/all"}...
[07:15:27.097] INFO WebRTC: Sending raw: {"message_id":"fe82ac46-060f-4a0e-8c29-f78e99aaa760","command":"players/all"}...
[07:15:27.451] INFO Sendspin: Sending message: client/state
[07:15:27.454] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:32.094] INFO RemoteBridge: API WS → WebRTC: {"message_id":"ca7127d1-b81f-491f-9b4f-4fe4d537a028","command":"players/all"}...
[07:15:32.095] INFO WebRTC: Sending raw: {"message_id":"ca7127d1-b81f-491f-9b4f-4fe4d537a028","command":"players/all"}...
[07:15:32.451] INFO Sendspin: Sending message: client/state
[07:15:32.453] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:35.036] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:98 recv:97, Sendspin sent:20 recv:5, lastRecv:11s ago, state:RemoteBridgeState.connected
[07:15:37.096] INFO RemoteBridge: API WS → WebRTC: {"message_id":"dc246cec-c91b-49c3-aba3-c671829c5b83","command":"players/all"}...
[07:15:37.097] INFO WebRTC: Sending raw: {"message_id":"dc246cec-c91b-49c3-aba3-c671829c5b83","command":"players/all"}...
[07:15:37.451] INFO Sendspin: Sending message: client/state
[07:15:37.454] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:38.864] INFO Signaling: Sending ping
[07:15:38.866] INFO Signaling: Received message type: ping
[07:15:38.866] INFO Signaling: Received ping from server, sending pong
[07:15:38.866] INFO Signaling: Sending pong
[07:15:38.923] INFO Signaling: Received message type: pong
[07:15:42.095] INFO RemoteBridge: API WS → WebRTC: {"message_id":"648deed5-cb9c-469e-8d2b-1799baf258c0","command":"players/all"}...
[07:15:42.096] INFO WebRTC: Sending raw: {"message_id":"648deed5-cb9c-469e-8d2b-1799baf258c0","command":"players/all"}...
[07:15:42.451] INFO Sendspin: Sending message: client/state
[07:15:42.454] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:45.033] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:100 recv:97, Sendspin sent:22 recv:5, lastRecv:21s ago, state:RemoteBridgeState.connected
[07:15:45.044] INFO RemoteBridge: API WS → WebRTC: {"message_id":"1f9e9205-3aa4-4bb2-9c21-97ca0caa7068","command":"info"}...
[07:15:45.044] INFO WebRTC: Sending raw: {"message_id":"1f9e9205-3aa4-4bb2-9c21-97ca0caa7068","command":"info"}...
[07:15:47.095] INFO RemoteBridge: API WS → WebRTC: {"message_id":"806369ef-4368-4ed9-b2fb-1f4b330e5c35","command":"players/all"}...
[07:15:47.095] INFO WebRTC: Sending raw: {"message_id":"806369ef-4368-4ed9-b2fb-1f4b330e5c35","command":"players/all"}...
[07:15:47.450] INFO Sendspin: Sending message: client/time
[07:15:47.453] INFO Sendspin: Sending message: client/state
[07:15:47.455] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/time","payload":{"client_transmitted":1769238947450787}}...
[07:15:47.455] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:47.475] INFO WebRTC: Sendspin text message: {"payload":{"client_transmitted":1769238947450787,"server_received":120489380913,"server_transmitted...
[07:15:47.475] INFO RemoteBridge: WebRTC Sendspin text → WS: {"payload":{"client_transmitted":1769238947450787,"server_received":120489380913,"server_transmitted...
[07:15:47.477] INFO Sendspin: Received message type: server/time
[07:15:47.477] INFO Sendspin: Received server/time response
[07:15:50.944] INFO Error getting tracks: TimeoutException: Command timeout: music/tracks/library_items
[07:15:50.944] INFO ✅ Force sync complete
[07:15:52.097] INFO RemoteBridge: API WS → WebRTC: {"message_id":"b530af4e-7b35-41b6-a01a-d156febe61ab","command":"players/all"}...
[07:15:52.098] INFO WebRTC: Sending raw: {"message_id":"b530af4e-7b35-41b6-a01a-d156febe61ab","command":"players/all"}...
[07:15:52.452] INFO Sendspin: Sending message: client/state
[07:15:52.455] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:53.866] INFO Signaling: Sending ping
[07:15:53.916] INFO Signaling: Received message type: pong
[07:15:54.019] INFO ⚠️ player_queues/get not available: TimeoutException: Command timeout: player_queues/get
[07:15:54.022] INFO RemoteBridge: API WS → WebRTC: {"message_id":"1871e992-315b-4765-8264-93c71fd528ca","command":"player_queues/items","args":{"queue_...
[07:15:54.023] INFO WebRTC: Sending raw: {"message_id":"1871e992-315b-4765-8264-93c71fd528ca","command":"player_queues/items","args":{"queue_...
[07:15:55.033] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:104 recv:97, Sendspin sent:25 recv:6, lastRecv:7s ago, state:RemoteBridgeState.connected
[07:15:55.044] INFO Heartbeat timeout - connection may be stale
[07:15:57.099] INFO RemoteBridge: API WS → WebRTC: {"message_id":"9cb714f4-d0fe-472a-8c97-d1395269145a","command":"players/all"}...
[07:15:57.099] INFO WebRTC: Sending raw: {"message_id":"9cb714f4-d0fe-472a-8c97-d1395269145a","command":"players/all"}...
[07:15:57.101] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:15:57.450] INFO Sendspin: Sending message: client/state
[07:15:57.451] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:15:59.107] INFO RemoteBridge: API WS → WebRTC: {"message_id":"bbdfaa36-d999-4c07-967d-56e899ade1f2","command":"players/all"}...
[07:15:59.108] INFO WebRTC: Sending raw: {"message_id":"bbdfaa36-d999-4c07-967d-56e899ade1f2","command":"players/all"}...
[07:16:02.099] INFO RemoteBridge: API WS → WebRTC: {"message_id":"d43c2031-2ddd-4892-920d-0a14669a433a","command":"players/all"}...
[07:16:02.100] INFO WebRTC: Sending raw: {"message_id":"d43c2031-2ddd-4892-920d-0a14669a433a","command":"players/all"}...
[07:16:02.104] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:02.450] INFO Sendspin: Sending message: client/state
[07:16:02.453] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:04.112] INFO RemoteBridge: API WS → WebRTC: {"message_id":"87cf45ad-806d-4771-a234-74dad94ee9d5","command":"players/all"}...
[07:16:04.113] INFO WebRTC: Sending raw: {"message_id":"87cf45ad-806d-4771-a234-74dad94ee9d5","command":"players/all"}...
[07:16:05.033] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:108 recv:97, Sendspin sent:27 recv:6, lastRecv:17s ago, state:RemoteBridgeState.connected
[07:16:07.097] INFO RemoteBridge: API WS → WebRTC: {"message_id":"5c3ecf10-f3ad-4ab6-8c27-ff63c25f9d66","command":"players/all"}...
[07:16:07.098] INFO WebRTC: Sending raw: {"message_id":"5c3ecf10-f3ad-4ab6-8c27-ff63c25f9d66","command":"players/all"}...
[07:16:07.102] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:07.450] INFO Sendspin: Sending message: client/state
[07:16:07.453] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:08.866] INFO Signaling: Sending ping
[07:16:08.883] INFO Signaling: Received message type: ping
[07:16:08.883] INFO Signaling: Received ping from server, sending pong
[07:16:08.883] INFO Signaling: Sending pong
[07:16:08.921] INFO Signaling: Received message type: pong
[07:16:09.106] INFO RemoteBridge: API WS → WebRTC: {"message_id":"f5c7a4df-d13a-434c-b7e9-bef017ff1095","command":"players/all"}...
[07:16:09.107] INFO WebRTC: Sending raw: {"message_id":"f5c7a4df-d13a-434c-b7e9-bef017ff1095","command":"players/all"}...
[07:16:09.283] INFO 🔄 Fetching fresh album tracks for library_124...
[07:16:09.298] INFO RemoteBridge: API WS → WebRTC: {"message_id":"5e55900b-8dce-41ce-a883-a65739214ccc","command":"music/albums/album_tracks","args":{"...
[07:16:09.298] INFO WebRTC: Sending raw: {"message_id":"5e55900b-8dce-41ce-a883-a65739214ccc","command":"music/albums/album_tracks","args":{"...
[07:16:09.651] INFO RemoteBridge: API WS → WebRTC: {"message_id":"f4e55001-9eea-46f8-ba97-bf293a6e11a7","command":"music/item_by_uri","args":{"uri":"li...
[07:16:09.652] INFO WebRTC: Sending raw: {"message_id":"f4e55001-9eea-46f8-ba97-bf293a6e11a7","command":"music/item_by_uri","args":{"uri":"li...
[07:16:12.099] INFO RemoteBridge: API WS → WebRTC: {"message_id":"b037a315-7b95-4c04-bc14-4383290a70fb","command":"players/all"}...
[07:16:12.099] INFO WebRTC: Sending raw: {"message_id":"b037a315-7b95-4c04-bc14-4383290a70fb","command":"players/all"}...
[07:16:12.101] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:12.450] INFO Sendspin: Sending message: client/state
[07:16:12.454] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:14.109] INFO RemoteBridge: API WS → WebRTC: {"message_id":"c89e8f6e-c1f2-477b-820e-478c55c10328","command":"players/all"}...
[07:16:14.109] INFO WebRTC: Sending raw: {"message_id":"c89e8f6e-c1f2-477b-820e-478c55c10328","command":"players/all"}...
[07:16:15.032] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:114 recv:97, Sendspin sent:29 recv:6, lastRecv:27s ago, state:RemoteBridgeState.connected
[07:16:15.043] INFO RemoteBridge: API WS → WebRTC: {"message_id":"30995a3b-a62c-491e-b37b-20937f4bf277","command":"info"}...
[07:16:15.044] INFO WebRTC: Sending raw: {"message_id":"30995a3b-a62c-491e-b37b-20937f4bf277","command":"info"}...
[07:16:17.095] INFO RemoteBridge: API WS → WebRTC: {"message_id":"4d0ef616-d44c-4953-b08f-e09572e57d74","command":"players/all"}...
[07:16:17.096] INFO WebRTC: Sending raw: {"message_id":"4d0ef616-d44c-4953-b08f-e09572e57d74","command":"players/all"}...
[07:16:17.100] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:17.450] INFO Sendspin: Sending message: client/time
[07:16:17.453] INFO Sendspin: Sending message: client/state
[07:16:17.456] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/time","payload":{"client_transmitted":1769238977450510}}...
[07:16:17.456] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:17.477] INFO WebRTC: Sendspin text message: {"payload":{"client_transmitted":1769238977450510,"server_received":120519381283,"server_transmitted...
[07:16:17.477] INFO RemoteBridge: WebRTC Sendspin text → WS: {"payload":{"client_transmitted":1769238977450510,"server_received":120519381283,"server_transmitted...
[07:16:17.479] INFO Sendspin: Received message type: server/time
[07:16:17.479] INFO Sendspin: Received server/time response
[07:16:19.105] INFO RemoteBridge: API WS → WebRTC: {"message_id":"81bc89cf-54a2-4df6-abf7-9fe2ad07aaca","command":"players/all"}...
[07:16:19.105] INFO WebRTC: Sending raw: {"message_id":"81bc89cf-54a2-4df6-abf7-9fe2ad07aaca","command":"players/all"}...
[07:16:22.097] INFO RemoteBridge: API WS → WebRTC: {"message_id":"07e65d39-f0f7-441f-9f9d-e2dfbbad2e55","command":"players/all"}...
[07:16:22.098] INFO WebRTC: Sending raw: {"message_id":"07e65d39-f0f7-441f-9f9d-e2dfbbad2e55","command":"players/all"}...
[07:16:22.100] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:22.454] INFO Sendspin: Sending message: client/state
[07:16:22.457] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:23.864] INFO Signaling: Sending ping
[07:16:23.908] INFO Signaling: Received message type: pong
[07:16:24.022] INFO Error getting queue: TimeoutException: Command timeout: player_queues/items
[07:16:24.023] INFO ⏱️ Starting idle service timer (30 min)
[07:16:24.023] INFO ⏱️ PositionTracker.clear() called
[07:16:24.023] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:16:24.042] INFO 💾 Persisted playback state to database
[07:16:24.104] INFO RemoteBridge: API WS → WebRTC: {"message_id":"8642ba7e-12c4-430b-871c-25d53def1acd","command":"players/all"}...
[07:16:24.105] INFO WebRTC: Sending raw: {"message_id":"8642ba7e-12c4-430b-871c-25d53def1acd","command":"players/all"}...
[07:16:25.032] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:119 recv:97, Sendspin sent:32 recv:7, lastRecv:7s ago, state:RemoteBridgeState.connected
[07:16:25.046] INFO Heartbeat timeout - connection may be stale
[07:16:27.096] INFO RemoteBridge: API WS → WebRTC: {"message_id":"3d948902-6473-4cf7-b883-33fd8054ce04","command":"players/all"}...
[07:16:27.096] INFO WebRTC: Sending raw: {"message_id":"3d948902-6473-4cf7-b883-33fd8054ce04","command":"players/all"}...
[07:16:27.098] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:27.451] INFO Sendspin: Sending message: client/state
[07:16:27.454] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:29.104] INFO RemoteBridge: API WS → WebRTC: {"message_id":"37195a8b-51b3-47f7-8a1c-f58d2d132401","command":"players/all"}...
[07:16:29.105] INFO WebRTC: Sending raw: {"message_id":"37195a8b-51b3-47f7-8a1c-f58d2d132401","command":"players/all"}...
[07:16:29.107] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:16:32.098] INFO RemoteBridge: API WS → WebRTC: {"message_id":"8e919e2a-eaf9-43c5-94a8-610d7721552d","command":"players/all"}...
[07:16:32.099] INFO WebRTC: Sending raw: {"message_id":"8e919e2a-eaf9-43c5-94a8-610d7721552d","command":"players/all"}...
[07:16:32.108] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:32.454] INFO Sendspin: Sending message: client/state
[07:16:32.457] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:33.117] INFO RemoteBridge: API WS → WebRTC: {"message_id":"9cc832ab-142c-4c7c-b694-f668d9e804d6","command":"players/all"}...
[07:16:33.117] INFO WebRTC: Sending raw: {"message_id":"9cc832ab-142c-4c7c-b694-f668d9e804d6","command":"players/all"}...
[07:16:34.114] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:16:34.114] INFO RemoteBridge: API WS → WebRTC: {"message_id":"c5380733-44a3-45c0-8328-5477ad81a67b","command":"players/all"}...
[07:16:34.114] INFO WebRTC: Sending raw: {"message_id":"c5380733-44a3-45c0-8328-5477ad81a67b","command":"players/all"}...
[07:16:35.032] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:124 recv:97, Sendspin sent:34 recv:7, lastRecv:17s ago, state:RemoteBridgeState.connected
[07:16:37.095] INFO RemoteBridge: API WS → WebRTC: {"message_id":"9dcde296-2bc8-4514-be5e-1cf2d42f696e","command":"players/all"}...
[07:16:37.096] INFO WebRTC: Sending raw: {"message_id":"9dcde296-2bc8-4514-be5e-1cf2d42f696e","command":"players/all"}...
[07:16:37.103] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:37.451] INFO Sendspin: Sending message: client/state
[07:16:37.455] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:38.121] INFO RemoteBridge: API WS → WebRTC: {"message_id":"5e050724-cadb-4850-937e-c8596b50520e","command":"players/all"}...
[07:16:38.122] INFO WebRTC: Sending raw: {"message_id":"5e050724-cadb-4850-937e-c8596b50520e","command":"players/all"}...
[07:16:38.869] INFO Signaling: Sending ping
[07:16:38.885] INFO Signaling: Received message type: ping
[07:16:38.885] INFO Signaling: Received ping from server, sending pong
[07:16:38.885] INFO Signaling: Sending pong
[07:16:38.925] INFO Signaling: Received message type: pong
[07:16:39.107] INFO RemoteBridge: API WS → WebRTC: {"message_id":"64ab7b97-5b2b-4e33-b3ea-d793303b1c00","command":"players/all"}...
[07:16:39.108] INFO WebRTC: Sending raw: {"message_id":"64ab7b97-5b2b-4e33-b3ea-d793303b1c00","command":"players/all"}...
[07:16:39.109] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:16:39.286] INFO Error getting album tracks: TimeoutException: Command timeout: music/albums/album_tracks
[07:16:39.287] INFO ✅ Cached 0 tracks for album library_124
[07:16:39.654] INFO Error getting album by URI: TimeoutException: Command timeout: music/item_by_uri
[07:16:42.098] INFO RemoteBridge: API WS → WebRTC: {"message_id":"e007dbaa-28e7-4a9b-98c0-895deb144b8e","command":"players/all"}...
[07:16:42.099] INFO WebRTC: Sending raw: {"message_id":"e007dbaa-28e7-4a9b-98c0-895deb144b8e","command":"players/all"}...
[07:16:42.105] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:42.451] INFO Sendspin: Sending message: client/state
[07:16:42.455] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:43.115] INFO RemoteBridge: API WS → WebRTC: {"message_id":"040cb630-3a9a-4e73-b674-be16bb1bf104","command":"players/all"}...
[07:16:43.116] INFO WebRTC: Sending raw: {"message_id":"040cb630-3a9a-4e73-b674-be16bb1bf104","command":"players/all"}...
[07:16:44.105] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:16:44.106] INFO RemoteBridge: API WS → WebRTC: {"message_id":"50789812-1651-4010-aad0-86cfbfc4f883","command":"players/all"}...
[07:16:44.106] INFO WebRTC: Sending raw: {"message_id":"50789812-1651-4010-aad0-86cfbfc4f883","command":"players/all"}...
[07:16:44.187] INFO 📱 App paused (backgrounded)
[07:16:45.032] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:130 recv:97, Sendspin sent:36 recv:7, lastRecv:27s ago, state:RemoteBridgeState.connected
[07:16:45.041] INFO RemoteBridge: API WS → WebRTC: {"message_id":"593a44ea-636a-4a0d-8a37-e871785175f6","command":"info"}...
[07:16:45.041] INFO WebRTC: Sending raw: {"message_id":"593a44ea-636a-4a0d-8a37-e871785175f6","command":"info"}...
[07:16:47.095] INFO RemoteBridge: API WS → WebRTC: {"message_id":"ebae3dd9-e5db-4509-b91e-eb579df823f3","command":"players/all"}...
[07:16:47.097] INFO WebRTC: Sending raw: {"message_id":"ebae3dd9-e5db-4509-b91e-eb579df823f3","command":"players/all"}...
[07:16:47.098] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:47.451] INFO Sendspin: Sending message: client/time
[07:16:47.457] INFO Sendspin: Sending message: client/state
[07:16:47.459] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/time","payload":{"client_transmitted":1769239007451395}}...
[07:16:47.459] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:47.483] INFO WebRTC: Sendspin text message: {"payload":{"client_transmitted":1769239007451395,"server_received":120549387706,"server_transmitted...
[07:16:47.483] INFO RemoteBridge: WebRTC Sendspin text → WS: {"payload":{"client_transmitted":1769239007451395,"server_received":120549387706,"server_transmitted...
[07:16:47.485] INFO Sendspin: Received message type: server/time
[07:16:47.485] INFO Sendspin: Received server/time response
[07:16:48.111] INFO RemoteBridge: API WS → WebRTC: {"message_id":"0c09ac7e-c315-4cac-bdd7-4ea356b4906f","command":"players/all"}...
[07:16:48.112] INFO WebRTC: Sending raw: {"message_id":"0c09ac7e-c315-4cac-bdd7-4ea356b4906f","command":"players/all"}...
[07:16:49.103] INFO RemoteBridge: API WS → WebRTC: {"message_id":"5ef3c176-38b3-42d0-b8cb-ac96ab4865eb","command":"players/all"}...
[07:16:49.105] INFO WebRTC: Sending raw: {"message_id":"5ef3c176-38b3-42d0-b8cb-ac96ab4865eb","command":"players/all"}...
[07:16:49.108] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:16:49.195] INFO Signaling: WebSocket closed
[07:16:49.196] INFO WebRTC: Signaling disconnected
[07:16:52.098] INFO RemoteBridge: API WS → WebRTC: {"message_id":"b1818840-f6b5-4031-a3f7-3b38ef316a25","command":"players/all"}...
[07:16:52.099] INFO WebRTC: Sending raw: {"message_id":"b1818840-f6b5-4031-a3f7-3b38ef316a25","command":"players/all"}...
[07:16:52.103] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:16:52.451] INFO Sendspin: Sending message: client/state
[07:16:52.457] INFO RemoteBridge: Sendspin WS text → WebRTC: {"type":"client/state","payload":{"player":{"state":"synchronized","volume":100,"muted":false,"posit...
[07:16:53.118] INFO RemoteBridge: API WS → WebRTC: {"message_id":"c92013f5-2016-47dd-95e5-addf451b48bf","command":"players/all"}...
[07:16:53.118] INFO WebRTC: Sending raw: {"message_id":"c92013f5-2016-47dd-95e5-addf451b48bf","command":"players/all"}...
[07:16:54.111] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:16:54.118] INFO RemoteBridge: API WS → WebRTC: {"message_id":"7601e66c-3384-48e8-bd32-a9a5883fe79e","command":"players/all"}...
[07:16:54.118] INFO WebRTC: Sending raw: {"message_id":"7601e66c-3384-48e8-bd32-a9a5883fe79e","command":"players/all"}...
[07:16:55.033] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:137 recv:97, Sendspin sent:39 recv:8, lastRecv:7s ago, state:RemoteBridgeState.connected
[07:16:55.043] INFO Heartbeat timeout - connection may be stale
[07:17:30.084] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:17:30.085] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:17:30.085] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:17:30.085] INFO ⚠️ Attempt 3/4 failed: TimeoutException: Command timeout: players/all. Retrying in 8s...
[07:17:30.085] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:17:30.085] INFO RemoteBridge: Health check - API DC:RTCDataChannelState.RTCDataChannelOpen, Sendspin:true, WC:WebRTCConnectionState.connected, API sent:137 recv:97, Sendspin sent:39 recv:8, lastRecv:42s ago, state:RemoteBridgeState.connected
[07:17:30.085] INFO RemoteBridge: Health check detected stale connection - channel open but no data for 42s, forcing reconnect
[07:17:30.085] INFO RemoteBridge: WebRTC disconnected
[07:17:30.085] INFO RemoteBridge: Closing all WS clients with error: 4001 - Remote connection lost - reconnecting
[07:17:30.086] INFO RemoteBridge: State changed: RemoteBridgeState.connected -> RemoteBridgeState.reconnecting
[07:17:30.086] INFO RemoteBridge: Scheduling reconnect attempt 1/10 in 2s
[07:17:30.086] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:17:30.086] INFO ⚠️ Attempt 3/4 failed: TimeoutException: Command timeout: players/all. Retrying in 8s...
[07:17:30.086] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:17:30.086] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:17:30.086] INFO ⚠️ Attempt 3/4 failed: TimeoutException: Command timeout: players/all. Retrying in 8s...
[07:17:30.086] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:17:30.086] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:17:30.086] INFO Sendspin: Sending message: client/time
[07:17:30.086] INFO ⚠️ Attempt 3/4 failed: TimeoutException: Command timeout: players/all. Retrying in 8s...
[07:17:30.086] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:17:30.086] INFO ⚠️ Attempt 1/4 failed: TimeoutException: Command timeout: players/all. Retrying in 2s...
[07:17:30.086] INFO ⚠️ Attempt 3/4 failed: TimeoutException: Command timeout: players/all. Retrying in 8s...
[07:17:30.086] INFO ⚠️ Attempt 2/4 failed: TimeoutException: Command timeout: players/all. Retrying in 4s...
[07:17:30.086] INFO Sendspin: Sending message: client/state
[07:17:30.087] INFO WebRTC: ICE connection state: RTCIceConnectionState.RTCIceConnectionStateDisconnected
[07:17:30.087] INFO WebRTC: ICE disconnected, may recover
[07:17:30.087] INFO Received message: (error, error_code, details)
[07:17:30.087] INFO WebSocket connection closed
[07:17:30.087] INFO 📡 Disconnected - keeping cached players and data for instant resume
[07:17:30.087] INFO 🔄 Starting remote reconnect timer (30s interval)
[07:17:30.087] INFO ⚠️ Attempt 1/4 failed: Exception: Connection lost, reconnecting. Retrying in 2s...
[07:17:30.087] INFO ⚠️ Attempt 3/4 failed: Exception: Connection lost, reconnecting. Retrying in 8s...
[07:17:30.087] INFO Heartbeat failed: Exception: Connection lost, reconnecting
[07:17:30.087] INFO Sendspin: WebSocket closed
[07:17:30.087] INFO Sendspin: Scheduling reconnect attempt 1/10 in 3s
[07:17:30.087] INFO WebRTC: ICE connection state: RTCIceConnectionState.RTCIceConnectionStateFailed
[07:17:30.087] INFO WebRTC: Signaling error: ICE connection failed
[07:17:30.087] INFO WebRTC: State changed: WebRTCConnectionState.connected -> WebRTCConnectionState.failed
[07:17:30.087] INFO RemoteBridge: WebRTC state: WebRTCConnectionState.failed
[07:17:30.087] INFO RemoteBridge: WebRTC disconnected
[07:17:30.087] INFO RemoteBridge: Closing all WS clients with error: 4001 - Remote connection lost - reconnecting
[07:17:30.087] INFO RemoteBridge: Scheduling reconnect attempt 2/10 in 4s
[07:17:30.087] INFO RemoteBridge: WebRTC error: ICE connection failed
[07:17:30.087] INFO RemoteBridge: Error [RemoteBridgeErrorType.webrtcConnection]: ICE connection failed
[07:17:30.087] INFO WebRTC: Peer connection state: RTCPeerConnectionState.RTCPeerConnectionStateFailed
[07:17:30.087] INFO WebRTC: Signaling error: Peer connection failed
[07:17:30.087] INFO RemoteBridge: WebRTC error: Peer connection failed
[07:17:30.087] INFO RemoteBridge: Error [RemoteBridgeErrorType.webrtcConnection]: Peer connection failed
[07:17:30.145] DEBUG [ArtistCard] No MA image for "Kevin Ayers", scheduling fallback
[07:17:30.177] INFO 📱 App resumed - checking WebSocket connection...
[07:17:30.177] INFO 🔄 checkAndReconnect called - state: MAConnectionState.error
[07:17:30.177] INFO 🔄 Not connected, attempting reconnect to ws://127.0.0.1:33839 (remote: true)
[07:17:30.179] INFO Original server URL: ws://127.0.0.1:33839
[07:17:30.179] INFO Using WebSocket session ID: session_041651fb-9fe4-453c-8938-878c5f6abb42
[07:17:30.179] INFO Using port from URL: 33839
[07:17:30.179] INFO Attempting unsecure (WS) connection
[07:17:30.179] INFO Final WebSocket URL: ws://127.0.0.1:33839/ws?client_id=session_041651fb-9fe4-453c-8938-878c5f6abb42
[07:17:30.179] INFO Connection: No authentication configured
[07:17:30.180] INFO RemoteBridge: Incoming HTTP request: /ws
[07:17:30.180] INFO RemoteBridge: API client connected
[07:17:30.180] INFO RemoteBridge: Sending cached server hello to API client
[07:17:30.181] INFO Received message: (server_id, server_version, schema_version, ..., homeassistant_addon, onboard_done)
[07:17:30.181] INFO Received server info: 2.8.0b7 (schema: 28)
[07:17:30.181] INFO Server: Requires authentication
[07:17:30.181] INFO 🔗 WebSocket connected to MA server
[07:17:30.181] INFO 🔐 MA auth required, attempting authentication...
[07:17:30.181] INFO Connection: Connected to server
[07:17:30.181] INFO 🔄 Reconnection successful on attempt 1
[07:17:30.185] INFO 📊 _subscribeToPositionTracker called, existing subscription=false
[07:17:30.185] INFO 📊 Subscribing to positionStream for progress bar
[07:17:30.246] INFO 🔐 Trying stored MA token...
[07:17:30.246] INFO 🔐 Authenticating WebSocket with token...
[07:17:30.247] INFO RemoteBridge: API WS → WebRTC: {"message_id":"dfc35518-4223-47d4-9aa7-cd035f5838b0","command":"auth","args":{"token":"b4meLj5ay0wQB...
[07:17:30.247] INFO WebRTC: Sending raw: {"message_id":"dfc35518-4223-47d4-9aa7-cd035f5838b0","command":"auth","args":{"token":"b4meLj5ay0wQB...
[07:17:32.088] INFO ❌ Operation failed after 2 attempts: Exception: Not connected to Music Assistant server
[07:17:32.090] INFO Error getting players after retries: Exception: Not connected to Music Assistant server
[07:17:32.090] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:17:32.091] INFO ⏱️ PositionTracker: Anchor updated 0.0s -> 14.0s (playerChanged=true, playStateChanged=false, positionDiff=14.0, suspiciousReset=false, anchorStale=true)
[07:17:32.091] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.091] INFO ⏱️ PositionTracker: Emitting position 14s (anchor=14.0s, playing=false)
[07:17:32.091] INFO ❌ Operation failed after 2 attempts: Exception: Not connected to Music Assistant server
[07:17:32.091] INFO Error getting players after retries: Exception: Not connected to Music Assistant server
[07:17:32.091] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:17:32.092] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:17:32.092] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.092] INFO ❌ Operation failed after 2 attempts: Exception: Not connected to Music Assistant server
[07:17:32.092] INFO Error getting players after retries: Exception: Not connected to Music Assistant server
[07:17:32.092] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:17:32.092] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:17:32.093] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.093] INFO ❌ Operation failed after 2 attempts: Exception: Not connected to Music Assistant server
[07:17:32.093] INFO Error getting players after retries: Exception: Not connected to Music Assistant server
[07:17:32.093] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:17:32.093] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:17:32.093] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.094] INFO ❌ Operation failed after 2 attempts: Exception: Not connected to Music Assistant server
[07:17:32.094] INFO Error getting players after retries: Exception: Not connected to Music Assistant server
[07:17:32.094] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:17:32.094] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:17:32.094] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.094] INFO ❌ Operation failed after 2 attempts: Exception: Not connected to Music Assistant server
[07:17:32.095] INFO Error getting players after retries: Exception: Not connected to Music Assistant server
[07:17:32.095] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:17:32.095] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:17:32.095] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.095] INFO ⚠️ player_queues/get not available: Exception: Not authenticated to Music Assistant server
[07:17:32.095] INFO ⚠️ player_queues/get not available: Exception: Not authenticated to Music Assistant server
[07:17:32.095] INFO ⚠️ player_queues/get not available: Exception: Not authenticated to Music Assistant server
[07:17:32.095] INFO ⚠️ player_queues/get not available: Exception: Not authenticated to Music Assistant server
[07:17:32.096] INFO ⚠️ player_queues/get not available: Exception: Not authenticated to Music Assistant server
[07:17:32.096] INFO ⚠️ player_queues/get not available: Exception: Not authenticated to Music Assistant server
[07:17:32.096] INFO Error getting queue: Exception: Not authenticated to Music Assistant server
[07:17:32.096] INFO Error getting queue: Exception: Not authenticated to Music Assistant server
[07:17:32.096] INFO Error getting queue: Exception: Not authenticated to Music Assistant server
[07:17:32.096] INFO Error getting queue: Exception: Not authenticated to Music Assistant server
[07:17:32.096] INFO Error getting queue: Exception: Not authenticated to Music Assistant server
[07:17:32.096] INFO Error getting queue: Exception: Not authenticated to Music Assistant server
[07:17:32.097] INFO ⏱️ Starting idle service timer (30 min)
[07:17:32.097] INFO ⏱️ PositionTracker.clear() called
[07:17:32.097] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.097] INFO ⏱️ Starting idle service timer (30 min)
[07:17:32.097] INFO ⏱️ PositionTracker.clear() called
[07:17:32.097] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.097] INFO ⏱️ Starting idle service timer (30 min)
[07:17:32.097] INFO ⏱️ PositionTracker.clear() called
[07:17:32.097] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.097] INFO ⏱️ Starting idle service timer (30 min)
[07:17:32.097] INFO ⏱️ PositionTracker.clear() called
[07:17:32.097] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.098] INFO ⏱️ Starting idle service timer (30 min)
[07:17:32.098] INFO ⏱️ PositionTracker.clear() called
[07:17:32.098] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.098] INFO ⏱️ Starting idle service timer (30 min)
[07:17:32.098] INFO ⏱️ PositionTracker.clear() called
[07:17:32.098] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.104] INFO ❌ Operation failed after 2 attempts: Exception: Not connected to Music Assistant server
[07:17:32.104] INFO Error getting players after retries: Exception: Not connected to Music Assistant server
[07:17:32.104] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:17:32.104] INFO ⏱️ PositionTracker: Anchor updated 0.0s -> 14.0s (playerChanged=true, playStateChanged=false, positionDiff=14.0, suspiciousReset=false, anchorStale=false)
[07:17:32.104] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.104] INFO ⏱️ PositionTracker: Emitting position 14s (anchor=14.0s, playing=false)
[07:17:32.104] INFO ❌ Operation failed after 1 attempts: Exception: Not authenticated to Music Assistant server
[07:17:32.104] INFO Error getting players after retries: Exception: Not authenticated to Music Assistant server
[07:17:32.104] INFO ⏱️ PositionTracker.updateFromServer: pos=14.0s, isPlaying=false, playerId=ensemble_Chris_CP11.251
[07:17:32.104] INFO ⏱️ PositionTracker: Anchor updated 14.0s -> 14.0s (playerChanged=false, playStateChanged=false, positionDiff=0.0, suspiciousReset=false, anchorStale=false)
[07:17:32.105] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.105] INFO ⚠️ player_queues/get not available: Exception: Not authenticated to Music Assistant server
[07:17:32.105] INFO ⚠️ player_queues/get not available: Exception: Not authenticated to Music Assistant server
[07:17:32.105] INFO Error getting queue: Exception: Not authenticated to Music Assistant server
[07:17:32.105] INFO Error getting queue: Exception: Not authenticated to Music Assistant server
[07:17:32.105] INFO ⏱️ Starting idle service timer (30 min)
[07:17:32.105] INFO ⏱️ PositionTracker.clear() called
[07:17:32.105] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
[07:17:32.105] INFO ⏱️ Starting idle service timer (30 min)
[07:17:32.105] INFO ⏱️ PositionTracker.clear() called
[07:17:32.105] INFO ⏱️ PositionTracker: Stopping interpolation timer (was null)
Reference in New Issue View Git Blame Copy Permalink