Feb 08 12:28:08 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:08-05:00" level=trace msg="sent dealer ping"
Feb 08 12:28:08 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:08-05:00" level=trace msg="received dealer pong"
Feb 08 12:28:11 volumio-barn volumio[1192]: verbose: New Socket.io Connection to 192.168.1.228:3000 from 192.168.1.175 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8
Feb 08 12:28:11 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 12:28:11 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 12:28:11 volumio-barn volumio[1192]: info: Discovery: Getting this device information
Feb 08 12:28:11 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:11 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=trace msg="emitting websocket event: not_playing"
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ"
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:playlist:761f0pO762b1UhBnR4sJyw","uri":"spotify:track:6x6Hrt5EnvqLIaGzkrDg2D","play_origin":"playlist"}}
Feb 08 12:28:13 volumio-barn volumio[1192]: error: Failed to decode event: not_playing
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=trace msg="emitting websocket event: will_play"
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=info msg="loaded track \"A Somber Mystery\" (paused: false, position: 0ms, duration: 125468ms, prefetched: true)" uri="spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ"
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:761f0pO762b1UhBnR4sJyw","uri":"spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ","play_origin":"playlist"}}
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=trace msg="scheduling prefetch in 96s"
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=trace msg="emitting websocket event: metadata"
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ","name":"A Somber Mystery","artist_names":["Schammasch"],"album_name":"The Maldoror Chants: Old Ocean","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02a50ff1b83eca91db2b23cbc9","position":0,"duration":125468,"release_date":"year:2024 month:10 day:25","track_number":2,"disc_number":1}}
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Feb 08 12:28:13 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:13-05:00" level=trace msg="emitting websocket event: playing"
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:761f0pO762b1UhBnR4sJyw","uri":"spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ","resume":false,"play_origin":"playlist"}}
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: PUSH STATE SPOTIFY
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: {"status":"play","service":"spop","title":"A Somber Mystery","artist":"Schammasch","album":"The Maldoror Chants: Old Ocean","albumart":"https://i.scdn.co/image/ab67616d00001e02a50ff1b83eca91db2b23cbc9","uri":"spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ","trackType":"spotify","seek":0,"duration":125,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Feb 08 12:28:13 volumio-barn volumio[1192]: info: CoreCommandRouter::servicePushState
Feb 08 12:28:13 volumio-barn volumio[1192]: info: CoreStateMachine::pushState
Feb 08 12:28:13 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 12:28:13 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioPushState
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: PUSH STATE SPOTIFY
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: {"status":"play","service":"spop","title":"A Somber Mystery","artist":"Schammasch","album":"The Maldoror Chants: Old Ocean","albumart":"https://i.scdn.co/image/ab67616d00001e02a50ff1b83eca91db2b23cbc9","uri":"spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ","trackType":"spotify","seek":1000,"duration":125,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Feb 08 12:28:13 volumio-barn volumio[1192]: info: CoreCommandRouter::servicePushState
Feb 08 12:28:13 volumio-barn volumio[1192]: info: CoreStateMachine::pushState
Feb 08 12:28:13 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 12:28:13 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioPushState
Feb 08 12:28:13 volumio-barn volumio[1192]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86
Feb 08 12:28:15 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 12:28:15 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 12:28:15 volumio-barn volumio[1192]: info: Discovery: Getting this device information
Feb 08 12:28:15 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:15 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 12:28:15 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Feb 08 12:28:15 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Feb 08 12:28:15 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone
Feb 08 12:28:15 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Feb 08 12:28:16 volumio-barn sudo[2334]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Feb 08 12:28:16 volumio-barn sudo[2324]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Feb 08 12:28:16 volumio-barn sudo[2324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:16 volumio-barn sudo[2334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:16 volumio-barn sudo[2339]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Feb 08 12:28:16 volumio-barn sudo[2339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:16 volumio-barn sudo[2328]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Feb 08 12:28:16 volumio-barn sudo[2328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:16 volumio-barn sudo[2343]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 08 12:28:16 volumio-barn sudo[2343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:16 volumio-barn sudo[2334]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:16 volumio-barn sudo[2347]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 08 12:28:16 volumio-barn sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:16 volumio-barn sudo[2343]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:16 volumio-barn sudo[2328]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:16 volumio-barn sudo[2324]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:16 volumio-barn sudo[2347]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:16 volumio-barn sudo[2339]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:16 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 08 12:28:16 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 08 12:28:16 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 12:28:16 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 12:28:16 volumio-barn volumio[1192]: info: Discovery: Getting this device information
Feb 08 12:28:16 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:16 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 12:28:16 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:17 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 08 12:28:17 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 08 12:28:17 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 12:28:17 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 12:28:17 volumio-barn volumio[1192]: info: Discovery: Getting this device information
Feb 08 12:28:17 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:17 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 12:28:17 volumio-barn volumio[1192]: verbose: New Socket.io Connection to 192.168.1.228:3000 from 192.168.1.175 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9
Feb 08 12:28:18 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 08 12:28:18 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 08 12:28:18 volumio-barn sudo[2356]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 08 12:28:18 volumio-barn sudo[2356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:18 volumio-barn sudo[2356]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:18 volumio-barn sudo[2358]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 08 12:28:18 volumio-barn sudo[2358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:18 volumio-barn sudo[2358]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:18 volumio-barn volumio[1192]: verbose: New Socket.io Connection to 192.168.1.228 from 192.168.1.175 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7a Build/BP3A.251105.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Feb 08 12:28:19 volumio-barn sudo[2362]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 08 12:28:19 volumio-barn sudo[2362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:19 volumio-barn sudo[2362]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:19 volumio-barn sudo[2364]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 08 12:28:19 volumio-barn sudo[2364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:28:19 volumio-barn sudo[2364]: pam_unix(sudo:session): session closed for user root
Feb 08 12:28:19 volumio-barn volumio[1192]: verbose: New Socket.io Connection to 192.168.1.228 from 192.168.1.175 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7a Build/BP3A.251105.015; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetVisibleSources
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 08 12:28:20 volumio-barn volumio[1192]: info: Received Get System Info
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 12:28:20 volumio-barn volumio[1192]: info: Discovery: Getting this device information
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 12:28:20 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:21 volumio-barn volumio[1192]: info: Listing playlists
Feb 08 12:28:21 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Feb 08 12:28:21 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 08 12:28:21 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Feb 08 12:28:22 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 08 12:28:22 volumio-barn volumio[1192]: info: Received Get System Info
Feb 08 12:28:22 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 12:28:22 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 12:28:22 volumio-barn volumio[1192]: info: Discovery: Getting this device information
Feb 08 12:28:22 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:22 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 12:28:23 volumio-barn volumio[1192]: verbose: New Socket.io Connection to 192.168.1.228:3000 from 192.168.1.175 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11
Feb 08 12:28:23 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 08 12:28:23 volumio-barn volumio[1192]: info: Received Get System Info
Feb 08 12:28:23 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 12:28:23 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 12:28:23 volumio-barn volumio[1192]: info: Discovery: Getting this device information
Feb 08 12:28:23 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:23 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 12:28:23 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Feb 08 12:28:27 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 08 12:28:27 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Feb 08 12:28:30 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Feb 08 12:28:30 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:30-05:00" level=debug msg="fetched chunk 4/8, size: 524288" uri="spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ"
Feb 08 12:28:38 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:38-05:00" level=trace msg="sent dealer ping"
Feb 08 12:28:38 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:38-05:00" level=trace msg="received dealer pong"
Feb 08 12:28:38 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 08 12:28:38 volumio-barn volumio[1192]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Feb 08 12:28:38 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Feb 08 12:28:38 volumio-barn volumio[1192]: info: Received Get System Version
Feb 08 12:28:38 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 08 12:28:38 volumio-barn volumio[1192]: info: Received Get System Info
Feb 08 12:28:38 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 12:28:38 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 12:28:38 volumio-barn volumio[1192]: info: Discovery: Getting this device information
Feb 08 12:28:38 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:28:38 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 12:28:43 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:43-05:00" level=debug msg="fetched chunk 5/8, size: 524288" uri="spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ"
Feb 08 12:28:46 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 08 12:28:46 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Feb 08 12:28:54 volumio-barn volumio[1192]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/bookworm/armhf
Feb 08 12:28:58 volumio-barn go-librespot[1639]: time="2026-02-08T12:28:58-05:00" level=debug msg="fetched chunk 6/8, size: 524288" uri="spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ"
Feb 08 12:29:08 volumio-barn go-librespot[1639]: time="2026-02-08T12:29:08-05:00" level=trace msg="received accesspoint ping"
Feb 08 12:29:08 volumio-barn go-librespot[1639]: time="2026-02-08T12:29:08-05:00" level=trace msg="received accesspoint pong ack"
Feb 08 12:29:08 volumio-barn go-librespot[1639]: time="2026-02-08T12:29:08-05:00" level=trace msg="sent dealer ping"
Feb 08 12:29:08 volumio-barn go-librespot[1639]: time="2026-02-08T12:29:08-05:00" level=trace msg="received dealer pong"
Feb 08 12:29:12 volumio-barn go-librespot[1639]: time="2026-02-08T12:29:12-05:00" level=debug msg="fetched chunk 7/8, size: 524288" uri="spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ"
Feb 08 12:29:19 volumio-barn volumio[1192]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/bookworm/armhf
Feb 08 12:29:19 volumio-barn sudo[2465]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh
Feb 08 12:29:19 volumio-barn sudo[2465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:29:19 volumio-barn sudo[2465]: pam_unix(sudo:session): session closed for user root
Feb 08 12:29:19 volumio-barn volumio[1192]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk
Feb 08 12:29:19 volumio-barn sudo[2468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk
Feb 08 12:29:19 volumio-barn sudo[2468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:29:19 volumio-barn sudo[2468]: pam_unix(sudo:session): session closed for user root
Feb 08 12:29:19 volumio-barn volumio[1192]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk
Feb 08 12:29:19 volumio-barn sudo[2471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk
Feb 08 12:29:19 volumio-barn sudo[2471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 12:29:20 volumio-barn startx[1633]: xinit: connection to X server lost
Feb 08 12:29:20 volumio-barn startx[1633]:
Feb 08 12:29:20 volumio-barn systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk...
Feb 08 12:29:20 volumio-barn startx[1633]: waiting for X server to shut down
Feb 08 12:29:20 volumio-barn startx[1634]: (II) Server terminated successfully (0). Closing log file.
Feb 08 12:29:21 volumio-barn startx[1633]: xinit: unexpected signal 15
Feb 08 12:29:21 volumio-barn systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE
Feb 08 12:29:21 volumio-barn systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'.
Feb 08 12:29:21 volumio-barn systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk.
Feb 08 12:29:21 volumio-barn systemd[1]: volumio-kiosk.service: Consumed 49.688s CPU time.
Feb 08 12:29:21 volumio-barn systemd[1]: Started volumio-kiosk.service - Volumio Kiosk.
Feb 08 12:29:21 volumio-barn sudo[2471]: pam_unix(sudo:session): session closed for user root
Feb 08 12:29:21 volumio-barn volumio[1192]: warn: [now-playing] MyBackgroundMonitor stopped
Feb 08 12:29:21 volumio-barn startx[2502]: X.Org X Server 1.21.1.7
Feb 08 12:29:21 volumio-barn startx[2502]: X Protocol Version 11, Revision 0
Feb 08 12:29:21 volumio-barn startx[2502]: Current Operating System: Linux volumio-barn 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l
Feb 08 12:29:21 volumio-barn startx[2502]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 numa=fake=1 system_heap.max_order=0 smsc95xx.macaddr=D8:3A:DD:22:E8:29 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no
Feb 08 12:29:21 volumio-barn startx[2502]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support)
Feb 08 12:29:21 volumio-barn startx[2502]: Current version of pixman: 0.44.0
Feb 08 12:29:21 volumio-barn startx[2502]: Before reporting problems, check http://wiki.x.org
Feb 08 12:29:21 volumio-barn startx[2502]: to make sure that you have the latest version.
Feb 08 12:29:21 volumio-barn startx[2502]: Markers: (--) probed, (**) from config file, (==) default setting,
Feb 08 12:29:21 volumio-barn startx[2502]: (++) from command line, (!!) notice, (II) informational,
Feb 08 12:29:21 volumio-barn startx[2502]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Feb 08 12:29:21 volumio-barn startx[2502]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Feb 8 12:29:21 2026
Feb 08 12:29:21 volumio-barn startx[2502]: (==) Using config directory: "/etc/X11/xorg.conf.d"
Feb 08 12:29:21 volumio-barn startx[2502]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Feb 08 12:29:24 volumio-barn go-librespot[1639]: time="2026-02-08T12:29:24-05:00" level=debug msg="fetched chunk 8/8, size: 520020" uri="spotify:track:1ytrSFz0ZqBDS4LlfpSWWQ"
Feb 08 12:29:28 volumio-barn volumio[1192]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11
Feb 08 12:29:29 volumio-barn volumio[1192]: info: Check plugin dependencies
Feb 08 12:29:29 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 08 12:29:29 volumio-barn volumio[1192]: info: Rename folder
Feb 08 12:29:29 volumio-barn volumio[1192]: info: Folder /tmp/downloaded_plugin.zip removed
Feb 08 12:29:29 volumio-barn volumio[1192]: info: Move to category
Feb 08 12:29:33 volumio-barn volumio[1192]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetVisibleSources
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 08 12:29:34 volumio-barn volumio[1192]: info: Received Get System Info
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 12:29:34 volumio-barn volumio[1192]: info: Discovery: Getting this device information
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 12:29:34 volumio-barn volumio[1192]: info: CoreCommandRouter::volumioGetState
Feb 08 12:29:34 volumio-barn volumio[1192]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 08 12:29:34 volumio-barn volumio[1192]: TypeError: Cannot read properties of undefined (reading 'plugin_type')
Feb 08 12:29:34 volumio-barn volumio[1192]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56)
Feb 08 12:29:34 volumio-barn volumio[1192]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1540:29)
Feb 08 12:29:34 volumio-barn volumio[1192]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1353:45)
Feb 08 12:29:34 volumio-barn volumio[1192]: at Socket.emit (node:events:514:28)
Feb 08 12:29:34 volumio-barn volumio[1192]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Feb 08 12:29:34 volumio-barn volumio[1192]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11)
Feb 08 12:29:34 volumio-barn volumio[1192]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 08 12:29:38 volumio-barn go-librespot[1639]: time="2026-02-08T12:29:38-05:00" level=trace msg="sent dealer ping"
Feb 08 12:29:38 volumio-barn go-librespot[1639]: time="2026-02-08T12:29:38-05:00" level=trace msg="received dealer pong"
Feb 08 12:29:39 volumio-barn sudo[2748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 12:28'
Feb 08 12:29:39 volumio-barn sudo[2748]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025"
VOLUMIO_VERSION="4.084"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"