Oct 17 07:56:03 volumio-maggie kernel: hwmon hwmon1: Undervoltage detected! Oct 17 07:56:06 volumio-maggie ntpd[959]: PROTO: 129.250.35.251 unlink local addr 192.168.1.176 -> Oct 17 07:56:07 volumio-maggie kernel: hwmon hwmon1: Voltage normalised Oct 17 07:56:09 volumio-maggie ntpd[959]: PROTO: 43.224.181.207 unlink local addr 192.168.1.176 -> Oct 17 07:56:13 volumio-maggie kernel: hwmon hwmon1: Undervoltage detected! Oct 17 07:56:15 volumio-maggie volumio[1347]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 17 07:56:15 volumio-maggie volumio[1347]: info: In handleBrowseUri, curUri=spotify:artist:2zgQujI3jnTTKJpj9Im76b Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preload queue cleared Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:4DvlcoeyHGPzlkSKZlVGSt Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:0nHetvYc4uSL5odNt7tz0u Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:1ncIANicQ0VwJpXkUIbEq0 Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:7r4P0RrNiK1zywL0bovrQC Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:2HTG5GhVK8YTndjEXgopWN Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:7Jh138clWHb9chSTifGmtG Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:1r7xzWEyzxEqHt4JJ9LkTW Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:1XMMPByN6wjBnIe0Ll583T Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:7jUVkntrzTQOpPjzjVaBop Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:42Lo0ocutu5gB5UpoLFpGW Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preload queue cleared Oct 17 07:56:16 volumio-maggie volumio[1347]: info: Preload queue cleared Oct 17 07:56:24 volumio-maggie kernel: hwmon hwmon1: Voltage normalised Oct 17 07:56:25 volumio-maggie volumio[1347]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 17 07:56:25 volumio-maggie volumio[1347]: info: In handleBrowseUri, curUri=spotify:album:5KhnWGAwLWbMRGUAiAcLl9 Oct 17 07:56:26 volumio-maggie volumio[1347]: info: Preload queue cleared Oct 17 07:56:26 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:0nHetvYc4uSL5odNt7tz0u Oct 17 07:56:26 volumio-maggie volumio[1347]: info: Exploding uri spotify:track:0nHetvYc4uSL5odNt7tz0u in service spop Oct 17 07:56:26 volumio-maggie volumio[1347]: SPOTIFY: EXPLODING URI:spotify:track:0nHetvYc4uSL5odNt7tz0u Oct 17 07:56:26 volumio-maggie volumio[1347]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","service":"spop","name":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b27323f63649dbde3ac5d24c8848","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 17 07:56:30 volumio-maggie systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Oct 17 07:56:31 volumio-maggie systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Oct 17 07:56:31 volumio-maggie systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Oct 17 07:56:31 volumio-maggie systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Oct 17 07:56:31 volumio-maggie volumio[1347]: info: Preload queue cleared Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::ClearQueue Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::stop Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CorePlayQueue::clearPlayQueue Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CorePlayQueue::saveQueue Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushQueue Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::addQueueItems Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CorePlayQueue::addQueueItems Oct 17 07:56:31 volumio-maggie volumio[1347]: info: Preload queue cleared Oct 17 07:56:31 volumio-maggie volumio[1347]: info: Adding Item to queue: spotify:track:0nHetvYc4uSL5odNt7tz0u Oct 17 07:56:31 volumio-maggie volumio[1347]: info: Using cached record of: spotify:track:0nHetvYc4uSL5odNt7tz0u Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushQueue Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CorePlayQueue::saveQueue Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::updateTrackBlock Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrackBlock Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPlay Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::play index 0 Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::stop Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::play index undefined Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CoreStateMachine::startPlaybackTimer Oct 17 07:56:31 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:31 volumio-maggie volumio[1347]: info: [1792184191889] ControllerSpotify::clearAddPlayTrack Oct 17 07:56:32 volumio-maggie kernel: hwmon hwmon1: Undervoltage detected! Oct 17 07:56:32 volumio-maggie volumio[1347]: info: CoreCommandRouter::servicePushState Oct 17 07:56:32 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:32 volumio-maggie volumio[1347]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d0000b27323f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":0,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:32 volumio-maggie volumio[1347]: verbose: CURRENT POSITION 0 Oct 17 07:56:32 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState stateService play Oct 17 07:56:32 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState currentStatus stop Oct 17 07:56:32 volumio-maggie volumio[1347]: info: Sending Spotify command with payload to local API: /player/play Oct 17 07:56:33 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:33+11:00" level=debug msg="resolved context of track" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:33 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:33+11:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:33 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:33+11:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:33 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:33+11:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 07:56:33 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:33+11:00" level=trace msg="emitting websocket event: will_play" Oct 17 07:56:33 volumio-maggie volumio[1347]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","play_origin":"go-librespot"}} Oct 17 07:56:33 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:33+11:00" level=debug msg="selected format OGG_VORBIS_320 (cbca15f355dc45cb8cc387bef10ccca7262b3abd)" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:33 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:33+11:00" level=debug msg="requested aes key for file cbca15f355dc45cb8cc387bef10ccca7262b3abd, gid: 0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:33 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:33+11:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=debug msg="fetched first chunk of 17, total size is 8814794 bytes" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=debug msg="track has no narration" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=debug msg="created new output device" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=info msg="loaded track \"Burden the Heavens\" (paused: false, position: 0ms, duration: 207452ms, prefetched: false)" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=trace msg="scheduling prefetch in 177s" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=trace msg="emitting websocket event: metadata" Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: received: {"type":"metadata","data":{"album_cover_url":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","album_name":"Burden the Heavens","artist_names":["Sabrina Jordan"],"bit_depth":null,"bitrate":320,"codec":"vorbis","disc_number":1,"duration":207452,"format":"OGG_VORBIS_320","name":"Burden the Heavens","position":0,"release_date":"year:2026 month:9 day:25","sample_rate":44100,"track_number":1,"uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u"}} Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 07:56:34 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:34+11:00" level=trace msg="emitting websocket event: playing" Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","resume":false,"play_origin":"go-librespot"}} Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: PUSH STATE SPOTIFY Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: {"status":"play","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":0,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreCommandRouter::servicePushState Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:34 volumio-maggie volumio[1347]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":0,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:34 volumio-maggie volumio[1347]: verbose: CURRENT POSITION 0 Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState stateService play Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState currentStatus play Oct 17 07:56:34 volumio-maggie volumio[1347]: info: Received an update from plugin. extracting info from payload Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreStateMachine::pushState Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushState Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreStateMachine::pushState Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushState Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: SPOTIFY VOLUME 51 Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: VOLUMIO VOLUME 50 Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: SPOTIFY VOLUME 51 Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: VOLUMIO VOLUME 50 Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: PUSH STATE SPOTIFY Oct 17 07:56:34 volumio-maggie volumio[1347]: SPOTIFY: {"status":"play","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":0,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreCommandRouter::servicePushState Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:34 volumio-maggie volumio[1347]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":0,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:34 volumio-maggie volumio[1347]: verbose: CURRENT POSITION 0 Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState stateService play Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState currentStatus play Oct 17 07:56:34 volumio-maggie volumio[1347]: info: Received an update from plugin. extracting info from payload Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CoreStateMachine::pushState Oct 17 07:56:34 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushState Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CoreStateMachine::pushState Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushState Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: SPOTIFY VOLUME 51 Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: VOLUMIO VOLUME 50 Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: SPOTIFY VOLUME 51 Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: VOLUMIO VOLUME 50 Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPause Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CoreStateMachine::pause Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CoreStateMachine::stPlaybackTimer Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CoreStateMachine::servicePause Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:35 volumio-maggie volumio[1347]: info: CoreCommandRouter::servicePause Oct 17 07:56:35 volumio-maggie volumio[1347]: info: Spotify Received pause Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: SPOTIFY PAUSE Oct 17 07:56:35 volumio-maggie volumio[1347]: SPOTIFY: {"status":"play","position":0,"title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d0000b27323f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","codec":"ogg","seek":0,"duration":207,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":50,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Oct 17 07:56:36 volumio-maggie volumio[1347]: info: Sending Spotify command to local API: /player/pause Oct 17 07:56:36 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:36+11:00" level=debug msg="pause track at 1691ms" Oct 17 07:56:36 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:36+11:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 07:56:36 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:36+11:00" level=trace msg="emitting websocket event: paused" Oct 17 07:56:36 volumio-maggie volumio[1347]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","play_origin":"go-librespot"}} Oct 17 07:56:36 volumio-maggie volumio[1347]: SPOTIFY: PUSH STATE SPOTIFY Oct 17 07:56:36 volumio-maggie volumio[1347]: SPOTIFY: {"status":"pause","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":1000,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreCommandRouter::servicePushState Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:36 volumio-maggie volumio[1347]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":1000,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:36 volumio-maggie volumio[1347]: verbose: CURRENT POSITION 0 Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState stateService pause Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState currentStatus pause Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreStateMachine::pushState Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushState Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreStateMachine::stPlaybackTimer Oct 17 07:56:36 volumio-maggie volumio[1347]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 17 07:56:36 volumio-maggie volumio[1347]: SPOTIFY: SPOTIFY VOLUME 51 Oct 17 07:56:36 volumio-maggie volumio[1347]: SPOTIFY: VOLUMIO VOLUME 50 Oct 17 07:56:36 volumio-maggie volumio[1347]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 17 07:56:36 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:36+11:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 07:56:36 volumio-maggie volumio[1347]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 17 07:56:36 volumio-maggie kernel: hwmon hwmon1: Voltage normalised Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPlay Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreStateMachine::play index undefined Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CoreStateMachine::startPlaybackTimer Oct 17 07:56:36 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:36 volumio-maggie volumio[1347]: info: Spotify Resume Oct 17 07:56:36 volumio-maggie volumio[1347]: info: Sending Spotify command to local API: /player/resume Oct 17 07:56:36 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:36+11:00" level=trace msg="seek to 1691ms (diff: 171ms, samples: 74573, bytes: 51085)" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:36 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:36+11:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Oct 17 07:56:36 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:36+11:00" level=debug msg="resume track at 1520ms" Oct 17 07:56:37 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:37+11:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 07:56:37 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:37+11:00" level=trace msg="scheduling prefetch in 176s" Oct 17 07:56:37 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:37+11:00" level=trace msg="emitting websocket event: playing" Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","resume":true,"play_origin":"go-librespot"}} Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: PUSH STATE SPOTIFY Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: {"status":"play","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":1000,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreCommandRouter::servicePushState Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:37 volumio-maggie volumio[1347]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":1000,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:37 volumio-maggie volumio[1347]: verbose: CURRENT POSITION 0 Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState stateService play Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState currentStatus pause Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreStateMachine::pushState Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushState Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: SPOTIFY VOLUME 51 Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: VOLUMIO VOLUME 50 Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 17 07:56:37 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:37+11:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: PUSH STATE SPOTIFY Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: {"status":"play","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":1000,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreCommandRouter::servicePushState Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:37 volumio-maggie volumio[1347]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Burden the Heavens","artist":"Sabrina Jordan","album":"Burden the Heavens","albumart":"https://i.scdn.co/image/ab67616d00001e0223f63649dbde3ac5d24c8848","uri":"spotify:track:0nHetvYc4uSL5odNt7tz0u","trackType":"spotify","seek":1000,"duration":207,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 17 07:56:37 volumio-maggie volumio[1347]: verbose: CURRENT POSITION 0 Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState stateService play Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreStateMachine::syncState currentStatus play Oct 17 07:56:37 volumio-maggie volumio[1347]: info: Received an update from plugin. extracting info from payload Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreStateMachine::pushState Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushState Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreStateMachine::pushState Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CorePlayQueue::getTrack 0 Oct 17 07:56:37 volumio-maggie volumio[1347]: info: CoreCommandRouter::volumioPushState Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: SPOTIFY VOLUME 51 Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: VOLUMIO VOLUME 50 Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: SPOTIFY VOLUME 51 Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: VOLUMIO VOLUME 50 Oct 17 07:56:37 volumio-maggie volumio[1347]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 17 07:56:45 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:45+11:00" level=debug msg="fetched chunk 4/16, size: 524288" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:56:55 volumio-maggie go-librespot[1764]: time="2026-10-17T07:56:55+11:00" level=debug msg="fetched chunk 5/16, size: 524288" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:57:05 volumio-maggie go-librespot[1764]: time="2026-10-17T07:57:05+11:00" level=debug msg="fetched chunk 6/16, size: 524288" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:57:10 volumio-maggie ntpd[959]: PROTO: 194.195.253.58 unlink local addr 192.168.1.176 -> Oct 17 07:57:16 volumio-maggie go-librespot[1764]: time="2026-10-17T07:57:16+11:00" level=debug msg="fetched chunk 7/16, size: 524288" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:57:19 volumio-maggie volumio[1347]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 17 07:57:19 volumio-maggie volumio[1347]: info: In handleBrowseUri, curUri=spotify:artist:5EQIBLPeVGQhTJxlk3HJdO Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preload queue cleared Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:6BHpxfMHOcL8bkXf0MJWf0 Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:6Q2EnUXSRof4XCPO3bwfZH Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:5JrR9FkhyuKIbh5gnvL6Oe Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:1PRaq8oenVkKVJPFbEy2N2 Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:6lI5gSYb2glYqp6SSCdN82 Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:5N4IY5Se4D8ghTxW6WG01P Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:4xD7I5nwfssgFJfq9WWo8A Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:2SM8RENrMCqHU2Cf7Je4NK Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:4C7g5Ocl9k45Iy4cDmCepB Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preloading song: spotify:track:6SSbSMZummTBEeXHFtCOQB Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preload queue cleared Oct 17 07:57:20 volumio-maggie volumio[1347]: info: Preload queue cleared Oct 17 07:57:21 volumio-maggie nmbd[1210]: [2026/10/17 07:57:21.683523, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 17 07:57:21 volumio-maggie nmbd[1210]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.176 for name WORKGROUP<1d>. Oct 17 07:57:21 volumio-maggie nmbd[1210]: This response was from IP 192.168.1.153, reporting an IP address of 192.168.1.153. Oct 17 07:57:22 volumio-maggie kernel: hwmon hwmon1: Undervoltage detected! Oct 17 07:57:26 volumio-maggie go-librespot[1764]: time="2026-10-17T07:57:26+11:00" level=debug msg="fetched chunk 8/16, size: 524288" uri="spotify:track:0nHetvYc4uSL5odNt7tz0u" Oct 17 07:57:26 volumio-maggie kernel: hwmon hwmon1: Voltage normalised Oct 17 07:57:32 volumio-maggie volumio[1347]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 17 07:57:32 volumio-maggie volumio[1347]: info: In handleBrowseUri, curUri=spotify:artist:2DDLFoPvrIea0nzyfYSgVU Oct 17 07:57:33 volumio-maggie volumio[1347]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 07:57:33 volumio-maggie volumio[1347]: TypeError: Cannot read properties of undefined (reading 'url') Oct 17 07:57:33 volumio-maggie volumio[1347]: at /data/plugins/music_service/spop/index.js:3360:60 Oct 17 07:57:33 volumio-maggie volumio[1347]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 17 07:57:33 volumio-maggie volumio[1347]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 07:57:37 volumio-maggie sudo[3668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 07:56' Oct 17 07:57:37 volumio-maggie sudo[3668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"