Dec 28 19:25:02 volumio go-librespot[22947]: time="2025-12-28T19:25:02+01:00" level=debug msg="fetched chunk 9/10, size: 524288" uri="spotify:track:29U7stRjqHU6rMiS8BfaI9" Dec 28 19:25:05 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:05 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:12 volumio go-librespot[22947]: time="2025-12-28T19:25:12+01:00" level=trace msg="sent dealer ping" Dec 28 19:25:13 volumio go-librespot[22947]: time="2025-12-28T19:25:13+01:00" level=trace msg="received dealer pong" Dec 28 19:25:15 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:15 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:15 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:15 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:15 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:15 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:15 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:15 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:15 volumio go-librespot[22947]: time="2025-12-28T19:25:15+01:00" level=debug msg="fetched chunk 10/10, size: 481188" uri="spotify:track:29U7stRjqHU6rMiS8BfaI9" Dec 28 19:25:18 volumio volumio[22634]: verbose: New Socket.io Connection to 192.168.178.99:3000 from 192.168.178.105 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 28 19:25:18 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 19:25:18 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 19:25:18 volumio volumio[22634]: info: Discovery: Getting this device information Dec 28 19:25:18 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:18 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:18 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 19:25:25 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:25 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:25 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:25 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:27 volumio volumio[22634]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 19:25:27 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 28 19:25:27 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 28 19:25:27 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 28 19:25:27 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 28 19:25:27 volumio volumio[22634]: info: Received Get System Version Dec 28 19:25:27 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 19:25:31 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:31 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:33 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:25:33 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:25:33 volumio volumio[22634]: info: CoreCommandRouter::volumioGetQueue Dec 28 19:25:33 volumio volumio[22634]: info: CoreStateMachine::getQueue Dec 28 19:25:33 volumio volumio[22634]: info: CorePlayQueue::getQueue Dec 28 19:25:33 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:25:33 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:25:33 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:25:33 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:25:35 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:35 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:35 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:35 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:35 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:35 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:35 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:35 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:42 volumio go-librespot[22947]: time="2025-12-28T19:25:42+01:00" level=trace msg="sent dealer ping" Dec 28 19:25:43 volumio go-librespot[22947]: time="2025-12-28T19:25:43+01:00" level=trace msg="received dealer pong" Dec 28 19:25:45 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:45 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:45 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:45 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:47 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:47 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:49 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:25:49 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:25:49 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:25:49 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:25:49 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:25:49 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:25:50 volumio volumio[22634]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 19:25:50 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 19:25:50 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 28 19:25:50 volumio sudo[619]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 28 19:25:50 volumio sudo[619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:25:50 volumio sudo[619]: pam_unix(sudo:session): session closed for user root Dec 28 19:25:50 volumio sudo[624]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 28 19:25:50 volumio sudo[624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:25:50 volumio sudo[624]: pam_unix(sudo:session): session closed for user root Dec 28 19:25:50 volumio sudo[632]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 28 19:25:50 volumio sudo[632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:25:50 volumio sudo[632]: pam_unix(sudo:session): session closed for user root Dec 28 19:25:50 volumio sudo[637]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 28 19:25:50 volumio sudo[637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:25:50 volumio sudo[637]: pam_unix(sudo:session): session closed for user root Dec 28 19:25:50 volumio sudo[644]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 19:25:50 volumio sudo[644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:25:50 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 28 19:25:50 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 28 19:25:50 volumio sudo[644]: pam_unix(sudo:session): session closed for user root Dec 28 19:25:50 volumio sudo[647]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 19:25:50 volumio sudo[647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:25:50 volumio sudo[647]: pam_unix(sudo:session): session closed for user root Dec 28 19:25:50 volumio sudo[650]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 19:25:50 volumio sudo[650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:25:53 volumio sudo[650]: pam_unix(sudo:session): session closed for user root Dec 28 19:25:55 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:55 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:55 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:25:55 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:25:55 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:55 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:55 volumio volumio[22634]: info: Listing playlists Dec 28 19:25:55 volumio volumio[22634]: info: Listing playlists Dec 28 19:26:02 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:02 volumio volumio[22634]: info: CorePlayQueue::getTrack 7 Dec 28 19:26:02 volumio volumio[22634]: info: Prefetching next song Dec 28 19:26:02 volumio volumio[22634]: info: [1766946362319] ControllerSpotify::prefetch Dec 28 19:26:02 volumio volumio[22634]: info: Sending Spotify command with payload to local API: /player/add_to_queue Dec 28 19:26:02 volumio go-librespot[22947]: time="2025-12-28T19:26:02+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 28 19:26:02 volumio go-librespot[22947]: time="2025-12-28T19:26:02+01:00" level=debug msg="prefetching next track" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:02 volumio go-librespot[22947]: time="2025-12-28T19:26:02+01:00" level=debug msg="selected format OGG_VORBIS_320 (b7ca8c87d3266ca47cc90e5bc195c2174ff047cb)" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:02 volumio go-librespot[22947]: time="2025-12-28T19:26:02+01:00" level=debug msg="requested aes key for file b7ca8c87d3266ca47cc90e5bc195c2174ff047cb, gid: 54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=debug msg="fetched first chunk of 16, total size is 8132548 bytes" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=info msg="prefetched track \"In the Mood - Live\" (duration: 209573ms)" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=trace msg="emitting websocket event: not_playing" Dec 28 19:26:04 volumio volumio[22634]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:track:1YQWosTIljIvxAgHWTp7KP","uri":"spotify:track:29U7stRjqHU6rMiS8BfaI9","play_origin":"go-librespot"}} Dec 28 19:26:04 volumio volumio[22634]: error: Failed to decode event: not_playing Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=trace msg="emitting websocket event: will_play" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=info msg="loaded track \"In the Mood - Live\" (paused: false, position: 1ms, duration: 209573ms, prefetched: true)" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:04 volumio volumio[22634]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:1YQWosTIljIvxAgHWTp7KP","uri":"spotify:track:54h1RKrrFJDsNOfhwmqu9o","play_origin":"go-librespot"}} Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=trace msg="scheduling prefetch in 180s" Dec 28 19:26:04 volumio go-librespot[22947]: time="2025-12-28T19:26:04+01:00" level=trace msg="emitting websocket event: metadata" Dec 28 19:26:04 volumio volumio[22634]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:54h1RKrrFJDsNOfhwmqu9o","name":"In the Mood - Live","artist_names":["Glenn Miller"],"album_name":"The Glenn Miller Carnegie Hall Concert (Live)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e027322bf3bcd991ebf28b8b352","position":1,"duration":209573,"release_date":"year:1939","track_number":8,"disc_number":1}} Dec 28 19:26:05 volumio go-librespot[22947]: time="2025-12-28T19:26:05+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 28 19:26:05 volumio go-librespot[22947]: time="2025-12-28T19:26:05+01:00" level=trace msg="emitting websocket event: playing" Dec 28 19:26:05 volumio volumio[22634]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:1YQWosTIljIvxAgHWTp7KP","uri":"spotify:track:54h1RKrrFJDsNOfhwmqu9o","resume":false,"play_origin":"go-librespot"}} Dec 28 19:26:05 volumio volumio[22634]: SPOTIFY: PUSH STATE SPOTIFY Dec 28 19:26:05 volumio volumio[22634]: SPOTIFY: {"status":"play","service":"spop","title":"In the Mood - Live","artist":"Glenn Miller","album":"The Glenn Miller Carnegie Hall Concert (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e027322bf3bcd991ebf28b8b352","uri":"spotify:track:54h1RKrrFJDsNOfhwmqu9o","trackType":"spotify","seek":1,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::servicePushState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"In the Mood - Live","artist":"Glenn Miller","album":"The Glenn Miller Carnegie Hall Concert (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e027322bf3bcd991ebf28b8b352","uri":"spotify:track:54h1RKrrFJDsNOfhwmqu9o","trackType":"spotify","seek":1,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 28 19:26:05 volumio volumio[22634]: verbose: CURRENT POSITION 6 Dec 28 19:26:05 volumio volumio[22634]: info: CoreStateMachine::syncState stateService play Dec 28 19:26:05 volumio volumio[22634]: info: CoreStateMachine::syncState currentStatus play Dec 28 19:26:05 volumio volumio[22634]: info: Received an update from plugin. extracting info from payload Dec 28 19:26:05 volumio volumio[22634]: info: CoreStateMachine::pushState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioPushState Dec 28 19:26:05 volumio volumio[22634]: info: MRS: Pushing multiroomSync output update for this device Dec 28 19:26:05 volumio volumio[22634]: info: MRS: Pushing multiroomSync output Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: info: CoreStateMachine::pushState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioPushState Dec 28 19:26:05 volumio volumio[22634]: info: MRS: Pushing multiroomSync output update for this device Dec 28 19:26:05 volumio volumio[22634]: info: MRS: Pushing multiroomSync output Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: info: PeppySpectrum ---peppyspectrum status play Dec 28 19:26:05 volumio volumio[22634]: info: PeppySpectrum ---peppyspectrum status play Dec 28 19:26:05 volumio sudo[699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 28 19:26:05 volumio volumio[22634]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 28 19:26:05 volumio volumio[22634]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 28 19:26:05 volumio sudo[699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:05 volumio sudo[701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 28 19:26:05 volumio sudo[701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:05 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 28 19:26:05 volumio sudo[699]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:05 volumio sudo[701]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:05 volumio volumio[22634]: info: peppyspectrum Daemon Started Dec 28 19:26:05 volumio volumio[22634]: info: peppyspectrum Daemon Started Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: SPOTIFY: PUSH STATE SPOTIFY Dec 28 19:26:05 volumio volumio[22634]: SPOTIFY: {"status":"play","service":"spop","title":"In the Mood - Live","artist":"Glenn Miller","album":"The Glenn Miller Carnegie Hall Concert (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e027322bf3bcd991ebf28b8b352","uri":"spotify:track:54h1RKrrFJDsNOfhwmqu9o","trackType":"spotify","seek":1,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::servicePushState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"In the Mood - Live","artist":"Glenn Miller","album":"The Glenn Miller Carnegie Hall Concert (Live)","albumart":"https://i.scdn.co/image/ab67616d00001e027322bf3bcd991ebf28b8b352","uri":"spotify:track:54h1RKrrFJDsNOfhwmqu9o","trackType":"spotify","seek":1,"duration":209,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 28 19:26:05 volumio volumio[22634]: verbose: CURRENT POSITION 6 Dec 28 19:26:05 volumio volumio[22634]: info: CoreStateMachine::syncState stateService play Dec 28 19:26:05 volumio volumio[22634]: info: CoreStateMachine::syncState currentStatus play Dec 28 19:26:05 volumio volumio[22634]: info: Received an update from plugin. extracting info from payload Dec 28 19:26:05 volumio volumio[22634]: info: CoreStateMachine::pushState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioPushState Dec 28 19:26:05 volumio volumio[22634]: info: MRS: Pushing multiroomSync output update for this device Dec 28 19:26:05 volumio volumio[22634]: info: MRS: Pushing multiroomSync output Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: info: CoreStateMachine::pushState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioPushState Dec 28 19:26:05 volumio volumio[22634]: info: MRS: Pushing multiroomSync output update for this device Dec 28 19:26:05 volumio volumio[22634]: info: MRS: Pushing multiroomSync output Dec 28 19:26:05 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:05 volumio volumio[22634]: info: CorePlayQueue::getTrack 6 Dec 28 19:26:05 volumio volumio[22634]: info: PeppySpectrum ---peppyspectrum status play Dec 28 19:26:05 volumio volumio[22634]: info: PeppySpectrum ---peppyspectrum status play Dec 28 19:26:05 volumio volumio[22634]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 28 19:26:05 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 28 19:26:05 volumio sudo[719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 28 19:26:05 volumio sudo[719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:05 volumio volumio[22634]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 28 19:26:05 volumio sudo[721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 28 19:26:05 volumio sudo[721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:05 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 28 19:26:05 volumio sudo[719]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:05 volumio volumio[22634]: info: peppyspectrum Daemon Started Dec 28 19:26:05 volumio sudo[721]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:05 volumio volumio[22634]: info: peppyspectrum Daemon Started Dec 28 19:26:05 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 28 19:26:06 volumio volumio[22634]: info: CoreStateMachine::startPlaybackTimer Dec 28 19:26:06 volumio volumio[22634]: info: CorePlayQueue::getTrack 7 Dec 28 19:26:07 volumio volumio[22634]: info: CoreStateMachine::pushState Dec 28 19:26:07 volumio volumio[22634]: info: CorePlayQueue::getTrack 7 Dec 28 19:26:07 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 19:26:07 volumio volumio[22634]: info: CoreCommandRouter::volumioPushState Dec 28 19:26:07 volumio volumio[22634]: info: MRS: Pushing multiroomSync output update for this device Dec 28 19:26:07 volumio volumio[22634]: info: MRS: Pushing multiroomSync output Dec 28 19:26:07 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:07 volumio volumio[22634]: info: CorePlayQueue::getTrack 7 Dec 28 19:26:07 volumio volumio[22634]: info: PeppySpectrum ---peppyspectrum status play Dec 28 19:26:07 volumio volumio[22634]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 28 19:26:07 volumio sudo[728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 28 19:26:07 volumio sudo[728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:07 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 28 19:26:07 volumio sudo[728]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:07 volumio volumio[22634]: info: peppyspectrum Daemon Started Dec 28 19:26:07 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 28 19:26:09 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:26:09 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:26:09 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:26:09 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:26:09 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:26:09 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:26:12 volumio go-librespot[22947]: time="2025-12-28T19:26:12+01:00" level=trace msg="sent dealer ping" Dec 28 19:26:13 volumio go-librespot[22947]: time="2025-12-28T19:26:13+01:00" level=trace msg="received dealer pong" Dec 28 19:26:14 volumio volumio[22634]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] Dec 28 19:26:14 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings Dec 28 19:26:14 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 28 19:26:14 volumio volumio[22634]: info: Discovery: Restarting Advertising due to device name change Dec 28 19:26:14 volumio sudo[762]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/resolv.conf.tail Dec 28 19:26:14 volumio sudo[762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:14 volumio sudo[762]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:14 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 28 19:26:14 volumio volumio[22634]: info: Discovery: Restarting Advertising due to device name change Dec 28 19:26:14 volumio sudo[764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 28 19:26:14 volumio sudo[764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:14 volumio sudo[767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Dec 28 19:26:14 volumio sudo[767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:14 volumio avahi-daemon[929]: Withdrawing address record for 192.168.178.99 on eth0. Dec 28 19:26:14 volumio avahi-daemon[929]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.178.99. Dec 28 19:26:14 volumio avahi-daemon[929]: Interface eth0.IPv4 no longer relevant for mDNS. Dec 28 19:26:14 volumio dhcpcd[1087]: eth0: pid 769 deleted IP address 192.168.178.99/24 Dec 28 19:26:14 volumio dhcpcd[1087]: eth0: deleting route to 192.168.178.0/24 Dec 28 19:26:14 volumio sudo[767]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:14 volumio dhcpcd[1087]: eth0: deleting default route via 192.168.178.1 Dec 28 19:26:14 volumio volumio[22634]: info: Discovery: A device disappeared from network Dec 28 19:26:14 volumio volumio[22634]: info: Discovery: Device volumio disappeared from network Dec 28 19:26:14 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 28 19:26:14 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 28 19:26:14 volumio sudo[776]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Dec 28 19:26:14 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 28 19:26:14 volumio sudo[776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:14 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:26:14 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 19:26:14 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 28 19:26:14 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 19:26:14 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 19:26:14 volumio dhcpcd[1087]: eth0: probing address 192.168.178.99/24 Dec 28 19:26:14 volumio kernel: macb 1f00100000.ethernet eth0: Link is Down Dec 28 19:26:14 volumio dhcpcd[1087]: eth0: carrier lost Dec 28 19:26:14 volumio kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock unregistered. Dec 28 19:26:14 volumio sudo[776]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:14 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 19:26:14 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 28 19:26:14 volumio sudo[794]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Dec 28 19:26:14 volumio sudo[794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:14 volumio welcome[785]: Resolved ip:[0] Dec 28 19:26:14 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 19:26:14 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:26:14 volumio kernel: macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) Dec 28 19:26:14 volumio kernel: macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode Dec 28 19:26:14 volumio kernel: macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. Dec 28 19:26:14 volumio sudo[794]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:14 volumio ifplugd(eth0)[1244]: Link beat lost. Dec 28 19:26:14 volumio wireless.js[799]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 28 19:26:14 volumio wireless.js[799]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 28 19:26:14 volumio wireless.js[799]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 28 19:26:14 volumio wireless.js[799]: WIRELESS.JS: Cleaning previous... Dec 28 19:26:14 volumio sudo[830]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 28 19:26:14 volumio sudo[830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 19:26:14 volumio sudo[830]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:14 volumio sudo[832]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 28 19:26:14 volumio sudo[832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 19:26:15 volumio sudo[832]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:15 volumio wireless.js[799]: WIRELESS.JS: Stopped aP Dec 28 19:26:15 volumio sudo[840]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 28 19:26:15 volumio sudo[840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:15 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 28 19:26:15 volumio sudo[840]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:15 volumio sudo[842]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 28 19:26:15 volumio sudo[842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:15 volumio sudo[842]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:15 volumio sudo[850]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 28 19:26:15 volumio sudo[850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:15 volumio sudo[850]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:15 volumio sudo[852]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 28 19:26:15 volumio sudo[852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:15 volumio volumio[22634]: info: Discovery: A device disappeared from network Dec 28 19:26:16 volumio ntpd[1147]: IO: Deleting interface #3 eth0, 192.168.178.99#123, interface stats: received=1579, sent=1589, dropped=0, active_time=160171 secs Dec 28 19:26:16 volumio ntpd[1147]: PROTO: 212.45.144.3 unlink local addr 192.168.178.99 -> Dec 28 19:26:16 volumio ntpd[1147]: PROTO: 172.232.209.103 unlink local addr 192.168.178.99 -> Dec 28 19:26:16 volumio ntpd[1147]: PROTO: 185.19.184.35 unlink local addr 192.168.178.99 -> Dec 28 19:26:16 volumio ntpd[1147]: PROTO: 162.159.200.123 unlink local addr 192.168.178.99 -> Dec 28 19:26:16 volumio ntpd[1147]: PROTO: 162.159.200.1 unlink local addr 192.168.178.99 -> Dec 28 19:26:16 volumio ntpd[1147]: PROTO: 212.6.50.243 unlink local addr 192.168.178.99 -> Dec 28 19:26:16 volumio ntpd[1147]: PROTO: 193.204.114.232 unlink local addr 192.168.178.99 -> Dec 28 19:26:17 volumio sudo[852]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:17 volumio wireless.js[799]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: IT Dec 28 19:26:17 volumio sudo[859]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 28 19:26:17 volumio sudo[859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:17 volumio sudo[859]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:17 volumio sudo[861]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set IT Dec 28 19:26:17 volumio sudo[861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:17 volumio sudo[861]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:17 volumio wireless.js[799]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: IT Dec 28 19:26:17 volumio wireless.js[799]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 28 19:26:17 volumio wireless.js[799]: WIRELESS.JS: Notified systemd about wireless ready Dec 28 19:26:17 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 28 19:26:17 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 28 19:26:17 volumio sudo[764]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:18 volumio dhcpcd[1087]: eth0: carrier acquired Dec 28 19:26:18 volumio kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Dec 28 19:26:18 volumio dhcpcd[1087]: eth0: IAID 9e:01:33:f1 Dec 28 19:26:18 volumio dhcpcd[1087]: eth0: probing address 192.168.178.99/24 Dec 28 19:26:18 volumio ifplugd(eth0)[1244]: Link beat detected. Dec 28 19:26:19 volumio volumio[22634]: info: Discovery: Started advertising with name: Volumio Dec 28 19:26:19 volumio volumio[22634]: compat.c: read() failed: Resource temporarily unavailable Dec 28 19:26:19 volumio volumio[22634]: info: Discovery: mDNS temporarily unavailable during network transition Dec 28 19:26:19 volumio volumio[22634]: info: Discovery: Started advertising with name: Volumio Dec 28 19:26:19 volumio volumio[22634]: compat.c: read() failed: Resource temporarily unavailable Dec 28 19:26:19 volumio volumio[22634]: info: Discovery: mDNS temporarily unavailable during network transition Dec 28 19:26:19 volumio dhcpcd[1087]: eth0: soliciting an IPv6 router Dec 28 19:26:21 volumio volumio[22634]: info: Volumio Network Manager: Network status updated: 0 Dec 28 19:26:22 volumio avahi-daemon[929]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.99. Dec 28 19:26:22 volumio avahi-daemon[929]: New relevant interface eth0.IPv4 for mDNS. Dec 28 19:26:22 volumio avahi-daemon[929]: Registering new address record for 192.168.178.99 on eth0.IPv4. Dec 28 19:26:22 volumio dhcpcd[1087]: eth0: using static address 192.168.178.99/24 Dec 28 19:26:22 volumio dhcpcd[1087]: eth0: adding route to 192.168.178.0/24 Dec 28 19:26:22 volumio dhcpcd[1087]: eth0: adding default route via 192.168.178.1 Dec 28 19:26:22 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:26:22 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 19:26:22 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 28 19:26:22 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 19:26:22 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 19:26:22 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 19:26:22 volumio welcome[908]: Resolved ip:[1] 192.168.178.99 Dec 28 19:26:22 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 19:26:22 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 19:26:22 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 28 19:26:22 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 28 19:26:22 volumio sudo[915]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 19:26:22 volumio sudo[915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:22 volumio sudo[915]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:23 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:23 volumio volumio[22634]: info: CorePlayQueue::getTrack 7 Dec 28 19:26:24 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 28 19:26:24 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 28 19:26:24 volumio volumio[22634]: info: Refreshing Cached IP Addresses Dec 28 19:26:24 volumio sudo[928]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 28 19:26:24 volumio sudo[928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:24 volumio sudo[928]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:24 volumio sudo[932]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 19:26:24 volumio sudo[932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:24 volumio sudo[936]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 19:26:24 volumio sudo[936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:24 volumio sudo[932]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:24 volumio sudo[936]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:24 volumio ntpd[1147]: IO: Listen normally on 4 eth0 192.168.178.99:123 Dec 28 19:26:24 volumio ntpd[1147]: IO: new interface(s) found: waking up resolver Dec 28 19:26:25 volumio go-librespot[22947]: time="2025-12-28T19:26:25+01:00" level=debug msg="fetched chunk 4/15, size: 524288" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 19:26:25 volumio volumio[22634]: info: Retrieving Cloud Streaming UI Dec 28 19:26:25 volumio volumio[22634]: info: Getting Tidal Cloud Configuration Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 19:26:25 volumio volumio[22634]: info: Getting Qobuz Cloud Configuration Dec 28 19:26:25 volumio volumio[22634]: info: Asking plugin for UI Config Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 19:26:25 volumio volumio[22634]: info: Getting Spotify Cloud Configuration Dec 28 19:26:25 volumio volumio[22634]: info: Asking plugin for UI Config Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 19:26:25 volumio volumio[22634]: info: Saving Spotify Acccount Dec 28 19:26:25 volumio volumio[22634]: info: Got it Dec 28 19:26:25 volumio volumio[22634]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 28 19:26:25 volumio volumio[22634]: info: Got Tidal Cloud Configuration Dec 28 19:26:25 volumio volumio[22634]: info: Got it Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 19:26:25 volumio volumio[22634]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 19:26:26 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 19:26:26 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 28 19:26:26 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:26:26 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:26:26 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:26:26 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:26:26 volumio volumio[22634]: info: Executing endpoint metavolumio Dec 28 19:26:26 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 19:26:26 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:26 volumio volumio[22634]: info: CorePlayQueue::getTrack 7 Dec 28 19:26:26 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:26 volumio volumio[22634]: info: CorePlayQueue::getTrack 7 Dec 28 19:26:26 volumio volumio[22634]: info: Listing playlists Dec 28 19:26:26 volumio volumio[22634]: info: Listing playlists Dec 28 19:26:26 volumio volumio[22634]: info: Listing playlists Dec 28 19:26:26 volumio volumio[22634]: info: Listing playlists Dec 28 19:26:27 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 28 19:26:27 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 28 19:26:27 volumio volumio[22634]: info: Refreshing Cached IP Addresses Dec 28 19:26:27 volumio sudo[948]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 28 19:26:27 volumio sudo[948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:27 volumio sudo[950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 19:26:27 volumio sudo[950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:27 volumio sudo[950]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:27 volumio sudo[954]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 19:26:27 volumio sudo[954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 19:26:27 volumio sudo[948]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:27 volumio sudo[954]: pam_unix(sudo:session): session closed for user root Dec 28 19:26:28 volumio go-librespot[22947]: time="2025-12-28T19:26:28+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX08jcQJXDnEQ" Dec 28 19:26:28 volumio go-librespot[22947]: time="2025-12-28T19:26:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:28 volumio go-librespot[22947]: time="2025-12-28T19:26:28+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E8NGETuczBckK" Dec 28 19:26:28 volumio go-librespot[22947]: time="2025-12-28T19:26:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:29 volumio go-librespot[22947]: time="2025-12-28T19:26:29+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:29 volumio go-librespot[22947]: time="2025-12-28T19:26:29+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E8Kx4zLEqEEPE" Dec 28 19:26:29 volumio go-librespot[22947]: time="2025-12-28T19:26:29+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E3ajz8FxqQNlK" Dec 28 19:26:29 volumio go-librespot[22947]: time="2025-12-28T19:26:29+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:29 volumio volumio[22634]: info: Discovery: Started advertising with name: Volumio Dec 28 19:26:29 volumio volumio[22634]: info: Discovery: Started advertising with name: Volumio Dec 28 19:26:30 volumio go-librespot[22947]: time="2025-12-28T19:26:30+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EQn2GRFTFMl2A" Dec 28 19:26:30 volumio go-librespot[22947]: time="2025-12-28T19:26:30+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:30 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 19:26:30 volumio go-librespot[22947]: time="2025-12-28T19:26:30+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:30 volumio go-librespot[22947]: time="2025-12-28T19:26:30+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E39fxHDEe2Phw" Dec 28 19:26:30 volumio volumio[22634]: info: Discovery: adding 1b6c8225-8d27-4b1b-a95e-fdd5bcff3aba Dec 28 19:26:30 volumio volumio[22634]: info: Discovery: Found device Volumio Dec 28 19:26:30 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:30 volumio volumio[22634]: info: CorePlayQueue::getTrack 7 Dec 28 19:26:30 volumio volumio[22634]: info: Discovery: this is already registered, 1b6c8225-8d27-4b1b-a95e-fdd5bcff3aba Dec 28 19:26:30 volumio volumio[22634]: info: Discovery: Found device Volumio Dec 28 19:26:30 volumio volumio[22634]: info: CoreCommandRouter::volumioGetState Dec 28 19:26:30 volumio volumio[22634]: info: CorePlayQueue::getTrack 7 Dec 28 19:26:30 volumio go-librespot[22947]: time="2025-12-28T19:26:30+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E36a0eCLTfTxN" Dec 28 19:26:30 volumio go-librespot[22947]: time="2025-12-28T19:26:30+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:30 volumio go-librespot[22947]: time="2025-12-28T19:26:30+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E37qxqWUj8m9L" Dec 28 19:26:30 volumio go-librespot[22947]: time="2025-12-28T19:26:30+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIeQG8xzHUNqL" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EQpj7X7UK8OOF" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIhhNAjSLG6ZC" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="fetched chunk 5/15, size: 524288" uri="spotify:track:54h1RKrrFJDsNOfhwmqu9o" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EQqA6klNdJvwx" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:31 volumio volumio[22634]: info: Volumio Network Manager: Network status updated: 1 Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E37gBOGH5nF9v" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E37WWzGhvSqOx" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:31 volumio go-librespot[22947]: time="2025-12-28T19:26:31+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EQnqst5TRi17F" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIVcvqY26MlS6" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EVKuMoAJjoTIw" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EQpgT26jgbgRI" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIWy7Uo5kyok8" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EQqMLgxRRiCOd" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E8P8FurHo3zkg" Dec 28 19:26:32 volumio go-librespot[22947]: time="2025-12-28T19:26:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:33 volumio go-librespot[22947]: time="2025-12-28T19:26:33+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4mkJn1DYa0mr" Dec 28 19:26:33 volumio go-librespot[22947]: time="2025-12-28T19:26:33+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:33 volumio go-librespot[22947]: time="2025-12-28T19:26:33+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E8MuCAv8I3o5g" Dec 28 19:26:33 volumio go-librespot[22947]: time="2025-12-28T19:26:33+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:33 volumio go-librespot[22947]: time="2025-12-28T19:26:33+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4EBdeEVqipSd" Dec 28 19:26:33 volumio go-librespot[22947]: time="2025-12-28T19:26:33+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:34 volumio go-librespot[22947]: time="2025-12-28T19:26:34+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4oCvywZiOBWl" Dec 28 19:26:34 volumio go-librespot[22947]: time="2025-12-28T19:26:34+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:34 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 28 19:26:34 volumio volumio[22634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 28 19:26:34 volumio volumio[22634]: compat.c: read() failed: Resource temporarily unavailable Dec 28 19:26:34 volumio volumio[22634]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:26:34 volumio volumio[22634]: Error: dns service error: unknown Dec 28 19:26:34 volumio volumio[22634]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 28 19:26:34 volumio volumio[22634]: errorCode: -65537 Dec 28 19:26:34 volumio volumio[22634]: } Dec 28 19:26:34 volumio volumio[22634]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 19:26:34 volumio go-librespot[22947]: time="2025-12-28T19:26:34+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4xptzFi20KXi" Dec 28 19:26:34 volumio go-librespot[22947]: time="2025-12-28T19:26:34+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:34 volumio go-librespot[22947]: time="2025-12-28T19:26:34+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4FCYxweFnGWe" Dec 28 19:26:34 volumio go-librespot[22947]: time="2025-12-28T19:26:34+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 28 19:26:34 volumio sudo[994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 19:25' Dec 28 19:26:34 volumio sudo[994]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"