-- Logs begin at Sun 2024-12-01 14:48:56 EST, end at Sun 2024-12-01 16:43:25 EST. -- Dec 01 16:42:02 bomboclat2 volumio[1013]: info: [yt-cast-receiver] (YouTube) Sender connected: Desktop (user: Sid) Dec 01 16:42:02 bomboclat2 volumio[1013]: info: [ytcr] ***** Sender connected ***** Dec 01 16:42:02 bomboclat2 volumio[1013]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 16:42:02 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.setVolume(): Dec 01 16:42:02 bomboclat2 volumio[1013]: { level: 100, muted: false } Dec 01 16:42:02 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:02 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:42:03 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.resume() Dec 01 16:42:03 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.play(): WSIb1ViLWDI @ 0s Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioStop Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::stop Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::stPlaybackTimer Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::updateTrackBlock Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrackBlock Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:03 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::serviceStop Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::serviceStop Dec 01 16:42:03 bomboclat2 volumio[1013]: info: Spotify Stop Dec 01 16:42:03 bomboclat2 volumio[1013]: SPOTIFY: SPOTIFY STOP Dec 01 16:42:03 bomboclat2 volumio[1013]: SPOTIFY: {"status":"play","position":10,"title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d0000b2738be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","codec":"ogg","seek":73575,"duration":288,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":35,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Dec 01 16:42:03 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/pause Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:03 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:03 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:03 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:03 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:03 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:03 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:03 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:03-05:00" level=debug msg="pause track at 127837ms" Dec 01 16:42:03 bomboclat2 volumio[1013]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 01 16:42:03 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:03-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:42:03 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:03-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:42:03 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:03-05:00" level=trace msg="emitting websocket event: paused" Dec 01 16:42:03 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:42:03 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:42:03 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:42:03 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:03 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:03 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:03 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:08 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.stop() Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.play(): WSIb1ViLWDI @ 0s Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioStop Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreStateMachine::stop Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreStateMachine::serviceStop Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::serviceStop Dec 01 16:42:08 bomboclat2 volumio[1013]: info: Spotify Stop Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: SPOTIFY STOP Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","codec":"ogg","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":35,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Dec 01 16:42:08 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/pause Dec 01 16:42:08 bomboclat2 volumio[1013]: verbose: UNSET VOLATILE: Service: spop Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:08 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.stop() Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:08 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:08 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:08 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:08 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:08 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:08-05:00" level=debug msg="pause track at 128909ms" Dec 01 16:42:08 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:08-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:42:08 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:08-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:42:08 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:08-05:00" level=trace msg="emitting websocket event: paused" Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:08 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:08 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:08 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:09 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.pause() Dec 01 16:42:26 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.resume() Dec 01 16:42:26 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.play(): WSIb1ViLWDI @ 0s Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioStop Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreStateMachine::stop Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreStateMachine::serviceStop Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::serviceStop Dec 01 16:42:26 bomboclat2 volumio[1013]: info: Spotify Stop Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: SPOTIFY STOP Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","codec":"ogg","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":35,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Dec 01 16:42:26 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/pause Dec 01 16:42:26 bomboclat2 volumio[1013]: verbose: UNSET VOLATILE: Service: spop Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:26 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.stop() Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:26 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:26 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:26 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:26 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:26 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:26-05:00" level=debug msg="pause track at 128909ms" Dec 01 16:42:26 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:26-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:42:26 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:26-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:42:26 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:26-05:00" level=trace msg="emitting websocket event: paused" Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:42:26 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:26 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:26 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:27 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:31 bomboclat2 sudo[9538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 16:42:31 bomboclat2 sudo[9538]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:42:31 bomboclat2 sudo[9538]: pam_unix(sudo:session): session closed for user root Dec 01 16:42:31 bomboclat2 sudo[9541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 16:42:31 bomboclat2 sudo[9541]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 16:42:31 bomboclat2 sudo[9541]: pam_unix(sudo:session): session closed for user root Dec 01 16:42:32 bomboclat2 volumio[1013]: verbose: New Socket.io Connection to 100.69.125.124 from 100.69.125.194 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 01 16:42:32 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:32 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 16:42:32 bomboclat2 volumio[1013]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 01 16:42:32 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 16:42:32 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:42:32 bomboclat2 volumio[1013]: info: Listing playlists Dec 01 16:42:32 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 01 16:42:32 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 01 16:42:32 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 01 16:42:32 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 16:42:36 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 16:42:36 bomboclat2 volumio[1013]: info: Received Get System Info Dec 01 16:42:36 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:42:36 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:42:36 bomboclat2 volumio[1013]: info: Discovery: Getting this device information Dec 01 16:42:36 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:36 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:42:44 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.play(): XutD0u4W2p8 @ 0s Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioStop Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreStateMachine::stop Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreStateMachine::serviceStop Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::serviceStop Dec 01 16:42:44 bomboclat2 volumio[1013]: info: Spotify Stop Dec 01 16:42:44 bomboclat2 volumio[1013]: SPOTIFY: SPOTIFY STOP Dec 01 16:42:44 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","codec":"ogg","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":35,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Dec 01 16:42:44 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/pause Dec 01 16:42:44 bomboclat2 volumio[1013]: verbose: UNSET VOLATILE: Service: spop Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:44 bomboclat2 volumio[1013]: info: [yt-cast-receiver] Player.stop() Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:44 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:45 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:45 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:45 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:45 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:45 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:45 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:45 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:45 bomboclat2 volumio[1013]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.vhpvff7TFTR3OxkQqVJZgelEwr12.1fdbc9e499f1fe98088151b816ecc0af.state.track' Dec 01 16:42:45 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:45-05:00" level=debug msg="pause track at 128909ms" Dec 01 16:42:45 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:45-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:42:45 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:45-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:42:45 bomboclat2 go-librespot[1281]: time="2024-12-01T16:42:45-05:00" level=trace msg="emitting websocket event: paused" Dec 01 16:42:45 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:42:45 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:42:45 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:42:45 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:42:45 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:42:45 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:42:45 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:42:45 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:42:45 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:42:45 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:42:45 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:57 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 16:42:57 bomboclat2 volumio[1013]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 01 16:42:57 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 01 16:42:57 bomboclat2 volumio[1013]: info: Received Get System Version Dec 01 16:42:57 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 16:42:57 bomboclat2 volumio[1013]: info: Received Get System Info Dec 01 16:42:57 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 16:42:57 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 16:42:57 bomboclat2 volumio[1013]: info: Discovery: Getting this device information Dec 01 16:42:57 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:42:57 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 16:43:01 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioVolatilePlay Dec 01 16:43:01 bomboclat2 volumio[1013]: info: CoreStateMachine::volatilePlay Dec 01 16:43:01 bomboclat2 volumio[1013]: info: Spotify Play Dec 01 16:43:01 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/resume Dec 01 16:43:01 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:01-05:00" level=trace msg="seek to 128909ms (diff: 92ms, samples: 5684886, bytes: 5620836)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:01 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:01-05:00" level=debug msg="resume track at 128728ms" Dec 01 16:43:01 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:01-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:01 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:01-05:00" level=trace msg="scheduling prefetch in 130s" Dec 01 16:43:01 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:01-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:01 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:01-05:00" level=trace msg="emitting websocket event: playing" Dec 01 16:43:01 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:43:01 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:01 bomboclat2 volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:01 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:01 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:01 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:43:01 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:01 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:01 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:01 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:01 bomboclat2 volumio[1013]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 01 16:43:02 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:02 bomboclat2 volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:02 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:02 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:02 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:02 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:02 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:02 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:03 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:03-05:00" level=debug msg="fetched chunk 14/23, size: 524288" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:04 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPause Dec 01 16:43:04 bomboclat2 volumio[1013]: info: CoreStateMachine::pause Dec 01 16:43:04 bomboclat2 volumio[1013]: info: CoreStateMachine::stPlaybackTimer Dec 01 16:43:04 bomboclat2 volumio[1013]: info: CoreStateMachine::servicePause Dec 01 16:43:04 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePause Dec 01 16:43:04 bomboclat2 volumio[1013]: info: Spotify Received pause Dec 01 16:43:04 bomboclat2 volumio[1013]: SPOTIFY: SPOTIFY PAUSE Dec 01 16:43:04 bomboclat2 volumio[1013]: SPOTIFY: {"status":"play","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","codec":"ogg","seek":125000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":35,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Dec 01 16:43:04 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/pause Dec 01 16:43:04 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:04-05:00" level=debug msg="pause track at 131333ms" Dec 01 16:43:04 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:04-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:04 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:04-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:04 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:04-05:00" level=trace msg="emitting websocket event: paused" Dec 01 16:43:04 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:43:04 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:04 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":127000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:04 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:04 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:04 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:43:04 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:04 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:04 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:04 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:04 bomboclat2 volumio[1013]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 01 16:43:05 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetQueue Dec 01 16:43:05 bomboclat2 volumio[1013]: info: CoreStateMachine::getQueue Dec 01 16:43:05 bomboclat2 volumio[1013]: info: CorePlayQueue::getQueue Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioClearQueue Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreStateMachine::ClearQueue Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreStateMachine::stop Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreStateMachine::serviceStop Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreCommandRouter::serviceStop Dec 01 16:43:09 bomboclat2 volumio[1013]: info: Spotify Stop Dec 01 16:43:09 bomboclat2 volumio[1013]: SPOTIFY: SPOTIFY STOP Dec 01 16:43:09 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","codec":"ogg","seek":127000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":35,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Dec 01 16:43:09 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/pause Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CorePlayQueue::clearPlayQueue Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CorePlayQueue::saveQueue Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:09 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:09 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushQueue Dec 01 16:43:09 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:09 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:09-05:00" level=debug msg="pause track at 132317ms" Dec 01 16:43:09 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:09-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:09 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:09-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:09 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:09-05:00" level=trace msg="emitting websocket event: paused" Dec 01 16:43:09 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:43:09 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:09 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":127000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:09 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:09 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:09 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:09 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:11 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:11-05:00" level=debug msg="handling resume player command from 92e38e32127da88b6a6bf4902460aca0ad31fbdd" Dec 01 16:43:11 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:11-05:00" level=trace msg="seek to 132317ms (diff: 81ms, samples: 5835179, bytes: 5778796)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:11 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:11-05:00" level=debug msg="resume track at 132236ms" Dec 01 16:43:11 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:11-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:11 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:11-05:00" level=trace msg="scheduling prefetch in 126s" Dec 01 16:43:11 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:11-05:00" level=debug msg="sending successful reply for dealer request" Dec 01 16:43:11 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:11-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:11 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:11-05:00" level=trace msg="emitting websocket event: playing" Dec 01 16:43:11 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:43:11 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:11 bomboclat2 volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":127000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:11 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:11 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:11 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:43:11 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:11 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:11 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:11 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:11 bomboclat2 volumio[1013]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 01 16:43:12 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:12 bomboclat2 volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":127000,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:12 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:12 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:12 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:12 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:12 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:12 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioNext Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreStateMachine::next Dec 01 16:43:14 bomboclat2 volumio[1013]: info: Spotify next Dec 01 16:43:14 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/next Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=trace msg="emitting websocket event: will_play" Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=debug msg="selected format OGG_VORBIS_320 (09adb044516d84f8a69cd51a8c3428972dd00e90)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=debug msg="requested aes key for file 09adb044516d84f8a69cd51a8c3428972dd00e90, gid: 7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=debug msg="fetched first chunk of 24, total size is 12518136 bytes" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=info msg="loaded track \"Through the Fire\" (paused: true, position: 0ms, duration: 288653ms, prefetched: false)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=trace msg="emitting websocket event: metadata" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=trace msg="emitting websocket event: stopped" Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","name":"Through the Fire","artist_names":["Chaka Khan"],"album_name":"I Feel for You","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","position":0,"duration":288653,"release_date":"year:1984","track_number":8,"disc_number":1}} Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: {"status":"stop","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":0,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:14 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:14 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 10 Dec 01 16:43:14 bomboclat2 volumio[1013]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":0,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:14 bomboclat2 volumio[1013]: verbose: CURRENT POSITION 10 Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreStateMachine::syncState stateService stop Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreStateMachine::syncState currentStatus play Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:14 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:14 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreStateMachine::stPlaybackTimer Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=debug msg="fetched chunk 1/23, size: 524288" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=trace msg="emitting websocket event: paused" Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":0,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:43:14 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:14 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:14 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:14 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:14 bomboclat2 volumio[1013]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 01 16:43:14 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:14-05:00" level=debug msg="fetched chunk 3/23, size: 524288" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=debug msg="fetched chunk 2/23, size: 524288" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioNext Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreStateMachine::next Dec 01 16:43:15 bomboclat2 volumio[1013]: info: Spotify next Dec 01 16:43:15 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/next Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=debug msg="loading track (paused: true, position: 0ms)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=trace msg="emitting websocket event: will_play" Dec 01 16:43:15 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=debug msg="selected format OGG_VORBIS_320 (09adb044516d84f8a69cd51a8c3428972dd00e90)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=debug msg="requested aes key for file 09adb044516d84f8a69cd51a8c3428972dd00e90, gid: 7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=debug msg="fetched first chunk of 24, total size is 12518136 bytes" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=info msg="loaded track \"Through the Fire\" (paused: true, position: 0ms, duration: 288653ms, prefetched: false)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=trace msg="emitting websocket event: metadata" Dec 01 16:43:15 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:15-05:00" level=trace msg="emitting websocket event: stopped" Dec 01 16:43:15 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","name":"Through the Fire","artist_names":["Chaka Khan"],"album_name":"I Feel for You","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","position":0,"duration":288653,"release_date":"year:1984","track_number":8,"disc_number":1}} Dec 01 16:43:15 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Dec 01 16:43:15 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:15 bomboclat2 volumio[1013]: SPOTIFY: {"status":"stop","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":0,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:15 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:15 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CorePlayQueue::getTrack 0 Dec 01 16:43:15 bomboclat2 volumio[1013]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":0,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:15 bomboclat2 volumio[1013]: verbose: CURRENT POSITION 0 Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreStateMachine::syncState stateService stop Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreStateMachine::syncState currentStatus pause Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:15 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:15 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:15 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:15 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:15 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=debug msg="fetched chunk 1/23, size: 524288" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=trace msg="emitting websocket event: paused" Dec 01 16:43:16 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:43:16 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:16 bomboclat2 volumio[1013]: SPOTIFY: {"status":"pause","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":0,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:16 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:16 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:16 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=debug msg="fetched chunk 3/23, size: 524288" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=debug msg="fetched chunk 2/23, size: 524288" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioVolatilePlay Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreStateMachine::volatilePlay Dec 01 16:43:16 bomboclat2 volumio[1013]: info: Spotify Play Dec 01 16:43:16 bomboclat2 volumio[1013]: info: Sending Spotify command to local API: /player/resume Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7gh2v4IHnxdiwSgA6xluhe" Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=debug msg="resume track at 473ms" Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=trace msg="scheduling prefetch in 259s" Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 01 16:43:16 bomboclat2 go-librespot[1281]: time="2024-12-01T16:43:16-05:00" level=trace msg="emitting websocket event: playing" Dec 01 16:43:16 bomboclat2 volumio[1013]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","play_origin":"go-librespot"}} Dec 01 16:43:16 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:16 bomboclat2 volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":0,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 16:43:16 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:16 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:16 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:16 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:16 bomboclat2 volumio[1013]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 01 16:43:17 bomboclat2 volumio[1013]: SPOTIFY: PUSH STATE SPOTIFY Dec 01 16:43:17 bomboclat2 volumio[1013]: SPOTIFY: {"status":"play","service":"spop","title":"Through the Fire","artist":"Chaka Khan","album":"I Feel for You","albumart":"https://i.scdn.co/image/ab67616d00001e028be5f9356ee08a1f9e67ff87","uri":"spotify:track:7gh2v4IHnxdiwSgA6xluhe","trackType":"spotify","seek":0,"duration":288,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 01 16:43:17 bomboclat2 volumio[1013]: info: CoreCommandRouter::servicePushState Dec 01 16:43:17 bomboclat2 volumio[1013]: info: CoreStateMachine::pushState Dec 01 16:43:17 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioPushState Dec 01 16:43:17 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Dec 01 16:43:17 bomboclat2 volumio[1013]: info: MRS: Pushing multiroomSync output Dec 01 16:43:17 bomboclat2 volumio[1013]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Dec 01 16:43:22 bomboclat2 volumio[1013]: verbose: New Socket.io Connection to 100.69.125.124 from 100.69.125.194 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 01 16:43:22 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetState Dec 01 16:43:22 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 16:43:22 bomboclat2 volumio[1013]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 01 16:43:22 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 16:43:22 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 16:43:22 bomboclat2 volumio[1013]: info: Listing playlists Dec 01 16:43:22 bomboclat2 volumio[1013]: info: CoreCommandRouter::volumioGetQueue Dec 01 16:43:22 bomboclat2 volumio[1013]: info: CoreStateMachine::getQueue Dec 01 16:43:22 bomboclat2 volumio[1013]: info: CorePlayQueue::getQueue Dec 01 16:43:24 bomboclat2 volumio[1013]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 01 16:43:24 bomboclat2 volumio[1013]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 01 16:43:24 bomboclat2 volumio[1013]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 16:43:24 bomboclat2 volumio[1013]: TypeError: Cannot read property 'then' of undefined Dec 01 16:43:24 bomboclat2 volumio[1013]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Dec 01 16:43:24 bomboclat2 volumio[1013]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2274:30) Dec 01 16:43:24 bomboclat2 volumio[1013]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1450:26) Dec 01 16:43:24 bomboclat2 volumio[1013]: at Socket.emit (events.js:315:20) Dec 01 16:43:24 bomboclat2 volumio[1013]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 01 16:43:24 bomboclat2 volumio[1013]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 01 16:43:24 bomboclat2 volumio[1013]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 16:43:25 bomboclat2 sudo[9671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 16:42 Dec 01 16:43:25 bomboclat2 sudo[9671]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"