Dec 09 11:03:03 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:03:12 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:03:12 volumio volumio[27449]: info: Listing playlists
Dec 09 11:03:12 volumio volumio[27449]: info: Listing playlists
Dec 09 11:03:20 volumio volumio[27449]: info: Starting Uninstall of plugin music_service - squeezelite_mc
Dec 09 11:03:20 volumio volumio[27449]: info: Uninstalling plugin squeezelite_mc
Dec 09 11:03:20 volumio volumio[27449]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true
Dec 09 11:03:20 volumio volumio[27449]: info: [squeezelite_mc] Proxy server stopped
Dec 09 11:03:20 volumio sudo[24973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite
Dec 09 11:03:20 volumio sudo[24973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 11:03:21 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Dec 09 11:03:21 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator...
Dec 09 11:03:21 volumio volumio[27449]: info: [squeezelite_mc] 'client' notification received from homeassistant - LMS HA Addon (10.0.5.10); type is 'disconnect'
Dec 09 11:03:21 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:03:21 volumio systemd[1]: squeezelite.service: Deactivated successfully.
Dec 09 11:03:21 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator.
Dec 09 11:03:21 volumio systemd[1]: squeezelite.service: Consumed 6.293s CPU time.
Dec 09 11:03:21 volumio sudo[24973]: pam_unix(sudo:session): session closed for user root
Dec 09 11:03:21 volumio volumio[27449]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true
Dec 09 11:03:21 volumio sudo[24994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite
Dec 09 11:03:21 volumio sudo[24994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 11:03:22 volumio sudo[24994]: pam_unix(sudo:session): session closed for user root
Dec 09 11:03:22 volumio volumio[27449]: info: Disabling plugin squeezelite_mc
Dec 09 11:03:22 volumio volumio[27449]: info: Checking if uninstall.sh is present
Dec 09 11:03:22 volumio volumio[27449]: info: Executing uninstall.sh
Dec 09 11:03:22 volumio sudo[25005]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/squeezelite_mc/uninstall.sh
Dec 09 11:03:22 volumio sudo[25005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 09 11:03:22 volumio systemd[1]: Reloading.
Dec 09 11:03:23 volumio volumio[27449]: info:
Dec 09 11:03:23 volumio volumio[27449]: ---------------------------- MPD announces system playlist update
Dec 09 11:03:23 volumio volumio[27449]: info: Ignoring MPD Status Update
Dec 09 11:03:23 volumio volumio[27449]: info:
Dec 09 11:03:23 volumio volumio[27449]: ---------------------------- MPD announces state update: player
Dec 09 11:03:23 volumio volumio[27449]: info: ControllerMpd::getState
Dec 09 11:03:23 volumio volumio[27449]: verbose: ControllerMpd::sendMpdCommand status
Dec 09 11:03:23 volumio volumio[27449]: info: ------------------------------ 6ms
Dec 09 11:03:23 volumio volumio[27449]: info: sendMpdCommand status took 5 milliseconds
Dec 09 11:03:23 volumio volumio[27449]: verbose: ControllerMpd::parseState
Dec 09 11:03:23 volumio volumio[27449]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 09 11:03:23 volumio volumio[27449]: info: sendMpdCommand playlistinfo took 1 milliseconds
Dec 09 11:03:23 volumio volumio[27449]: verbose: ControllerMpd::parseTrackInfo
Dec 09 11:03:23 volumio volumio[27449]: info: ControllerMpd::pushState
Dec 09 11:03:23 volumio volumio[27449]: info: CoreCommandRouter::servicePushState
Dec 09 11:03:23 volumio volumio[27449]: info: CorePlayQueue::getTrack 0
Dec 09 11:03:23 volumio volumio[27449]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6490393,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Piano Peace - What Child Is This (Greensleeves)","artist":"CHRISTMAS PIANO LULLABIES","album":null,"uri":"https://streams.calmradio.com/api/6482/320/stream?user=music%40thelodge.cloud&pass=444f5b7b5955689ddbe053f28d77408318f18d2c6da86cbfbe2ef0f5464da4dc91c36910fa3765798d2f4360a91eef2b","trackType":""}
Dec 09 11:03:23 volumio volumio[27449]: verbose: CURRENT POSITION 0
Dec 09 11:03:23 volumio volumio[27449]: info: CoreStateMachine::syncState stateService play
Dec 09 11:03:23 volumio volumio[27449]: info: CoreStateMachine::syncState currentStatus play
Dec 09 11:03:23 volumio volumio[27449]: info: Received an update from plugin. extracting info from payload
Dec 09 11:03:23 volumio volumio[27449]: info: CoreStateMachine::pushState
Dec 09 11:03:23 volumio volumio[27449]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 11:03:23 volumio volumio[27449]: info: CoreCommandRouter::volumioPushState
Dec 09 11:03:23 volumio volumio[27449]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 11:03:23 volumio volumio[27449]: info: MRS: Pushing multiroomSync output
Dec 09 11:03:23 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:03:23 volumio volumio[27449]: info: CoreStateMachine::pushState
Dec 09 11:03:23 volumio volumio[27449]: info: CoreCommandRouter::volumioPushState
Dec 09 11:03:23 volumio volumio[27449]: info: MRS: Pushing multiroomSync output update for this device
Dec 09 11:03:23 volumio volumio[27449]: info: MRS: Pushing multiroomSync output
Dec 09 11:03:23 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:03:23 volumio volumio[27449]: info: ------------------------------ 44ms
Dec 09 11:03:23 volumio volumio[27449]: info: FusionDsp - Volumio is playing
Dec 09 11:03:23 volumio volumio[27449]: info: FusionDsp - Volumio is playing
Dec 09 11:03:23 volumio volumio[27449]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50
Dec 09 11:03:23 volumio volumio[27449]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50
Dec 09 11:03:23 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:03:23 volumio systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 09 11:03:23 volumio systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 09 11:03:23 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 09 11:03:23 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 09 11:03:23 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 09 11:03:23 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 09 11:03:23 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Dec 09 11:03:23 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Dec 09 11:03:23 volumio systemd[1]: setdatetime-helper.service: Consumed 1.207s CPU time.
Dec 09 11:03:23 volumio sudo[25005]: pam_unix(sudo:session): session closed for user root
Dec 09 11:03:23 volumio volumio[27449]: info: Uninstall script completed
Dec 09 11:03:23 volumio volumio[27449]: info: Removing plugin squeezelite_mc from configuration
Dec 09 11:03:23 volumio volumio[27449]: info: Successfully removed squeezelite_mc configuration files
Dec 09 11:03:23 volumio volumio[27449]: info: Plugin folders cleanup
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning into folder /volumio/app/plugins/
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning category audio_interface
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning category miscellanea
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning category music_service
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning category plugins.json
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning category system_controller
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning category user_interface
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning into folder /data/plugins/
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning category audio_interface
Dec 09 11:03:23 volumio volumio[27449]: info: Scanning category music_service
Dec 09 11:03:23 volumio volumio[27449]: info: Cleaning folder for squeezelite_mc
Dec 09 11:03:24 volumio volumio[27449]: info: Scanning category user_interface
Dec 09 11:03:24 volumio volumio[27449]: info: Plugin folders cleanup completed
Dec 09 11:03:24 volumio volumio[27449]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 09 11:03:24 volumio volumio[27449]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"2.0.75","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Roon Bridge","name":"RoonBridge","category":"music_service","version":"2.0.0","icon":"fas fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"2.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Jellyfin","name":"jellyfin","category":"music_service","version":"2.0.0","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Your Brand","name":"myb","category":"music_service","version":"2.0.3","icon":"fa-solid fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Podcast","name":"podcast","category":"music_service","version":"3.2.1","icon":"fa-podcast","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.3.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"2.4.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"2.4.0","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.15","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"1.0.2","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.6.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}]
Dec 09 11:03:24 volumio volumio[27449]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 09 11:03:26 volumio go-librespot[27780]: time="2025-12-09T11:03:26-06:00" level=trace msg="sent dealer ping"
Dec 09 11:03:26 volumio go-librespot[27780]: time="2025-12-09T11:03:26-06:00" level=trace msg="received dealer pong"
Dec 09 11:03:32 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:03:32 volumio volumio[27449]: info: Listing playlists
Dec 09 11:03:32 volumio volumio[27449]: info: Listing playlists
Dec 09 11:03:42 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:03:52 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:03:52 volumio volumio[27449]: info: Listing playlists
Dec 09 11:03:52 volumio volumio[27449]: info: Listing playlists
Dec 09 11:03:56 volumio go-librespot[27780]: time="2025-12-09T11:03:56-06:00" level=trace msg="sent dealer ping"
Dec 09 11:03:56 volumio go-librespot[27780]: time="2025-12-09T11:03:56-06:00" level=trace msg="received dealer pong"
Dec 09 11:04:02 volumio volumio[27449]: info: CoreCommandRouter::volumioGetState
Dec 09 11:04:06 volumio volumio[27449]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 09 11:04:06 volumio volumio[27449]: TypeError: Cannot read properties of null (reading 'logger')
Dec 09 11:04:06 volumio volumio[27449]: at SqueezeliteMCContext.getLogger (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:65:86)
Dec 09 11:04:06 volumio volumio[27449]: at PlayerFinder._PlayerFinder_handleServerLost (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerFinder.js:130:36)
Dec 09 11:04:06 volumio volumio[27449]: at NotificationListener.emit (node:events:514:28)
Dec 09 11:04:06 volumio volumio[27449]: at Telnet. (/data/plugins/music_service/squeezelite_mc/node_modules/lms-cli-notifications/dist/cjs/NotificationListener.js:85:22)
Dec 09 11:04:06 volumio volumio[27449]: at Telnet.emit (node:events:526:35)
Dec 09 11:04:06 volumio volumio[27449]: at Socket. (/data/plugins/music_service/squeezelite_mc/node_modules/telnet-client/lib/index.js:174:22)
Dec 09 11:04:06 volumio volumio[27449]: at Socket.emit (node:events:514:28)
Dec 09 11:04:06 volumio volumio[27449]: at TCP. (node:net:337:12)
Dec 09 11:04:06 volumio volumio[27449]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 09 11:04:07 volumio sudo[25239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-09 11:03'
Dec 09 11:04:07 volumio sudo[25239]: 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"