Dec 12 23:19:00 volumio go-librespot[1487]: time="2025-12-12T23:19:00+01:00" level=debug msg="handling pause player command from 1460a95e1eb7c8e5c6a6a5dd52739fe98c1b038f"
Dec 12 23:19:00 volumio go-librespot[1487]: time="2025-12-12T23:19:00+01:00" level=debug msg="pause track at 46038ms"
Dec 12 23:19:00 volumio go-librespot[1487]: time="2025-12-12T23:19:00+01:00" level=debug msg="fetched chunk 7/23, size: 524288" uri="spotify:track:75IN3CtuZwTHTnZvYM4qnJ"
Dec 12 23:19:00 volumio go-librespot[1487]: time="2025-12-12T23:19:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Dec 12 23:19:00 volumio go-librespot[1487]: time="2025-12-12T23:19:00+01:00" level=debug msg="sending successful reply for dealer request"
Dec 12 23:19:00 volumio go-librespot[1487]: time="2025-12-12T23:19:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Dec 12 23:19:00 volumio go-librespot[1487]: time="2025-12-12T23:19:00+01:00" level=trace msg="emitting websocket event: paused"
Dec 12 23:19:00 volumio volumio[1087]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1DXbTxeAdrVG2l","uri":"spotify:track:75IN3CtuZwTHTnZvYM4qnJ","play_origin":"playlist"}}
Dec 12 23:19:00 volumio volumio[1087]: SPOTIFY: PUSH STATE SPOTIFY
Dec 12 23:19:00 volumio volumio[1087]: SPOTIFY: {"status":"pause","service":"spop","title":"My Name Is","artist":"Eminem","album":"The Slim Shady LP","albumart":"https://i.scdn.co/image/ab67616d00001e029bef45ee387f274ea3198c55","uri":"spotify:track:75IN3CtuZwTHTnZvYM4qnJ","trackType":"spotify","seek":44674,"duration":268,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Dec 12 23:19:00 volumio volumio[1087]: info: CoreCommandRouter::servicePushState
Dec 12 23:19:00 volumio volumio[1087]: info: CoreStateMachine::pushState
Dec 12 23:19:00 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 12 23:19:00 volumio volumio[1087]: info: CoreCommandRouter::volumioPushState
Dec 12 23:19:03 volumio volumio[1087]: info: Starting Uninstall of plugin user_interface - now_playing
Dec 12 23:19:03 volumio volumio[1087]: info: Uninstalling plugin now_playing
Dec 12 23:19:03 volumio volumio[1087]: warn: [now-playing] MyBackgroundMonitor stopped
Dec 12 23:19:03 volumio volumio[1087]: info: Disabling plugin now_playing
Dec 12 23:19:03 volumio volumio[1087]: info: Checking if uninstall.sh is present
Dec 12 23:19:03 volumio volumio[1087]: info: Executing uninstall.sh
Dec 12 23:19:03 volumio sudo[4079]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/uninstall.sh
Dec 12 23:19:03 volumio sudo[4079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 12 23:19:03 volumio sudo[4079]: pam_unix(sudo:session): session closed for user root
Dec 12 23:19:03 volumio volumio[1087]: info: Uninstall script completed
Dec 12 23:19:03 volumio volumio[1087]: info: Removing plugin now_playing from configuration
Dec 12 23:19:03 volumio volumio[1087]: info: Successfully removed now_playing configuration files
Dec 12 23:19:03 volumio volumio[1087]: info: Plugin folders cleanup
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning into folder /volumio/app/plugins/
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning category audio_interface
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning category miscellanea
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning category music_service
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning category plugins.json
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning category system_controller
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning category user_interface
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning into folder /data/plugins/
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning category music_service
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning category system_hardware
Dec 12 23:19:03 volumio volumio[1087]: info: Scanning category user_interface
Dec 12 23:19:03 volumio volumio[1087]: info: Cleaning folder for now_playing
Dec 12 23:19:07 volumio volumio[1087]: info: Starting Uninstall of plugin user_interface - now_playing
Dec 12 23:19:12 volumio volumio[1087]: info: Starting Uninstall of plugin system_hardware - mpd_oled
Dec 12 23:19:12 volumio volumio[1087]: info: Uninstalling plugin mpd_oled
Dec 12 23:19:12 volumio volumio[1087]: info: Disabling plugin mpd_oled
Dec 12 23:19:12 volumio volumio[1087]: info: Preparing to generate the ALSA configuration file
Dec 12 23:19:12 volumio volumio[1087]: info: Asound.conf file unchanged, so no further update is needed
Dec 12 23:19:12 volumio volumio[1087]: info: Output device has changed, restarting MPD
Dec 12 23:19:12 volumio volumio[1087]: info: Output device has changed, restarting Shairport Sync
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 12 23:19:12 volumio sudo[4106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 12 23:19:12 volumio sudo[4106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 12 23:19:12 volumio sudo[4106]: pam_unix(sudo:session): session closed for user root
Dec 12 23:19:12 volumio sudo[4108]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 12 23:19:12 volumio sudo[4108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 12 23:19:12 volumio volumio[1087]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 12 23:19:12 volumio volumio[1087]: info: Checking if uninstall.sh is present
Dec 12 23:19:12 volumio volumio[1087]: info: MPD Permissions set
Dec 12 23:19:12 volumio volumio[1087]: info: Executing uninstall.sh
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio sudo[4119]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/mpd_oled/uninstall.sh
Dec 12 23:19:12 volumio sudo[4119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 12 23:19:12 volumio volumio[1087]: info: Starting Shairport Sync
Dec 12 23:19:12 volumio sudo[4128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 12 23:19:12 volumio sudo[4128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 12 23:19:13 volumio systemd[1]: Reloading.
Dec 12 23:19:14 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 12 23:19:14 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 12 23:19:14 volumio sudo[4152]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm /etc/systemd/system/mpd_oled_plugin.service
Dec 12 23:19:14 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Dec 12 23:19:14 volumio sudo[4152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 12 23:19:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Dec 12 23:19:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Dec 12 23:19:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 12 23:19:14 volumio systemd[1]: shairport-sync.service: Consumed 6.604s CPU time.
Dec 12 23:19:14 volumio sudo[4152]: pam_unix(sudo:session): session closed for user root
Dec 12 23:19:14 volumio go-librespot[1487]: time="2025-12-12T23:19:14+01:00" level=trace msg="sent dealer ping"
Dec 12 23:19:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Dec 12 23:19:14 volumio sudo[4128]: pam_unix(sudo:session): session closed for user root
Dec 12 23:19:15 volumio volumio[1087]: info: Shairport-Sync Started
Dec 12 23:19:15 volumio go-librespot[1487]: time="2025-12-12T23:19:15+01:00" level=trace msg="received dealer pong"
Dec 12 23:19:15 volumio systemd[1]: Reloading.
Dec 12 23:19:16 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 12 23:19:16 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 12 23:19:17 volumio sudo[4193]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm -f /usr/local/bin/mpd_oled /usr/local/bin/mpd_oled_cava
Dec 12 23:19:17 volumio sudo[4193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 12 23:19:17 volumio sudo[4193]: pam_unix(sudo:session): session closed for user root
Dec 12 23:19:17 volumio sudo[4119]: pam_unix(sudo:session): session closed for user root
Dec 12 23:19:17 volumio volumio[1087]: info: Uninstall script completed
Dec 12 23:19:17 volumio volumio[1087]: info: Removing plugin mpd_oled from configuration
Dec 12 23:19:17 volumio volumio[1087]: info: Successfully removed mpd_oled configuration files
Dec 12 23:19:17 volumio volumio[1087]: info: Plugin folders cleanup
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning into folder /volumio/app/plugins/
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning category audio_interface
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning category miscellanea
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning category music_service
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning category plugins.json
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning category system_controller
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning category user_interface
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning into folder /data/plugins/
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning category music_service
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning category system_hardware
Dec 12 23:19:17 volumio volumio[1087]: info: Cleaning folder for mpd_oled
Dec 12 23:19:17 volumio volumio[1087]: info: Scanning category user_interface
Dec 12 23:19:17 volumio volumio[1087]: info: Cleaning folder for now_playing
Dec 12 23:19:21 volumio systemd[1]: mpd.service: Deactivated successfully.
Dec 12 23:19:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Dec 12 23:19:21 volumio systemd[1]: mpd.service: Consumed 10.045s CPU time.
Dec 12 23:19:21 volumio systemd[1]: mpd.socket: Deactivated successfully.
Dec 12 23:19:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Dec 12 23:19:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Dec 12 23:19:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Dec 12 23:19:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Dec 12 23:19:22 volumio sudo[4217]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Dec 12 23:19:22 volumio sudo[4217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 12 23:19:22 volumio sudo[4217]: pam_unix(sudo:session): session closed for user root
Dec 12 23:19:28 volumio mpd[4219]: 2025-12-12T23:19:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 12 23:19:28 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Dec 12 23:19:28 volumio sudo[4108]: pam_unix(sudo:session): session closed for user root
Dec 12 23:19:28 volumio volumio[1087]: error: updateQueue error: null
Dec 12 23:19:31 volumio sudo[4242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Dec 12 23:19:31 volumio sudo[4242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 12 23:19:31 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Dec 12 23:19:31 volumio systemd[1]: mpd_monitor.service: Deactivated successfully.
Dec 12 23:19:31 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Dec 12 23:19:31 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Dec 12 23:19:31 volumio mpd_monitor.sh[4244]: MPD Monitor Service: Starting MPD Monitor Service
Dec 12 23:19:31 volumio sudo[4242]: pam_unix(sudo:session): session closed for user root
Dec 12 23:19:31 volumio volumio[1087]: info: Successfully started MPD Monitor
Dec 12 23:19:36 volumio volumio[1087]: info: CorePlayQueue::getTrack 2
Dec 12 23:19:36 volumio volumio[1087]: info: CorePlayQueue::getTrack 3
Dec 12 23:19:36 volumio volumio[1087]: info: Prefetching next song
Dec 12 23:19:36 volumio volumio[1087]: info: DOING PREFETCH IN MPD
Dec 12 23:19:36 volumio volumio[1087]: verbose: ControllerMpd::sendMpdCommand add "NAS/Musique/Sonata Arctica/The Days of Grays/04. Flag In The Ground.mp3"
Dec 12 23:19:36 volumio volumio[1087]: info:
Dec 12 23:19:36 volumio volumio[1087]: ---------------------------- MPD announces system playlist update
Dec 12 23:19:36 volumio volumio[1087]: info: Ignoring MPD Status Update
Dec 12 23:19:36 volumio volumio[1087]: info: sendMpdCommand add "NAS/Musique/Sonata Arctica/The Days of Grays/04. Flag In The Ground.mp3" took 5 milliseconds
Dec 12 23:19:36 volumio volumio[1087]: verbose: ControllerMpd::sendMpdCommand consume 1
Dec 12 23:19:36 volumio volumio[1087]: info: ------------------------------ 28ms
Dec 12 23:19:36 volumio volumio[1087]: info:
Dec 12 23:19:36 volumio volumio[1087]: ---------------------------- MPD announces state update: options
Dec 12 23:19:36 volumio volumio[1087]: info: sendMpdCommand consume 1 took 26 milliseconds
Dec 12 23:19:36 volumio volumio[1087]: info: ControllerMpd::getState
Dec 12 23:19:36 volumio volumio[1087]: verbose: ControllerMpd::sendMpdCommand status
Dec 12 23:19:37 volumio volumio[1087]: info: sendMpdCommand status took 3 milliseconds
Dec 12 23:19:37 volumio volumio[1087]: verbose: ControllerMpd::parseState
Dec 12 23:19:37 volumio volumio[1087]: info: ControllerMpd::pushState
Dec 12 23:19:37 volumio volumio[1087]: info: CoreCommandRouter::servicePushState
Dec 12 23:19:37 volumio volumio[1087]: info: CoreStateMachine::pushState
Dec 12 23:19:37 volumio volumio[1087]: info: CoreCommandRouter::volumioPushState
Dec 12 23:19:37 volumio volumio[1087]: info: CorePlayQueue::getTrack 2
Dec 12 23:19:37 volumio volumio[1087]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 12 23:19:37 volumio volumio[1087]: verbose: CURRENT POSITION 2
Dec 12 23:19:37 volumio volumio[1087]: info: CoreStateMachine::syncState stateService stop
Dec 12 23:19:37 volumio volumio[1087]: info: CoreStateMachine::syncState currentStatus pause
Dec 12 23:19:37 volumio volumio[1087]: info: CoreStateMachine::pushState
Dec 12 23:19:37 volumio volumio[1087]: info: CoreCommandRouter::volumioPushState
Dec 12 23:19:37 volumio volumio[1087]: info: ------------------------------ 36ms
Dec 12 23:19:40 volumio ntpd[757]: PROTO: 172.232.63.219 unlink local addr 192.168.0.111 ->
Dec 12 23:19:41 volumio volumio[1087]: info: CoreStateMachine::startPlaybackTimer
Dec 12 23:19:41 volumio volumio[1087]: info: CorePlayQueue::getTrack 3
Dec 12 23:19:41 volumio volumio[1087]: info: CoreStateMachine::pushState
Dec 12 23:19:41 volumio volumio[1087]: info: CoreCommandRouter::volumioPushState
Dec 12 23:19:42 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
Dec 12 23:19:44 volumio go-librespot[1487]: time="2025-12-12T23:19:44+01:00" level=trace msg="sent dealer ping"
Dec 12 23:19:45 volumio go-librespot[1487]: time="2025-12-12T23:19:45+01:00" level=trace msg="received dealer pong"
Dec 12 23:20:03 volumio ntpd[757]: PROTO: 212.227.232.161 unlink local addr 192.168.0.111 ->
Dec 12 23:20:07 volumio volumio[1087]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 12 23:20:07 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 12 23:20:08 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 12 23:20:08 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 12 23:20:08 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs
Dec 12 23:20:08 volumio volumio[1087]: info: Received Get System Version
Dec 12 23:20:08 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 12 23:20:14 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard
Dec 12 23:20:14 volumio volumio[1087]: info: CoreCommandRouter::Reload Ui
Dec 12 23:20:14 volumio go-librespot[1487]: time="2025-12-12T23:20:14+01:00" level=trace msg="received accesspoint ping"
Dec 12 23:20:14 volumio go-librespot[1487]: time="2025-12-12T23:20:14+01:00" level=trace msg="received accesspoint pong ack"
Dec 12 23:20:14 volumio go-librespot[1487]: time="2025-12-12T23:20:14+01:00" level=trace msg="sent dealer ping"
Dec 12 23:20:15 volumio go-librespot[1487]: time="2025-12-12T23:20:15+01:00" level=trace msg="received dealer pong"
Dec 12 23:20:15 volumio sudo[4350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 12 23:20:15 volumio sudo[4352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 12 23:20:15 volumio sudo[4350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 12 23:20:15 volumio sudo[4352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 12 23:20:15 volumio sudo[4352]: pam_unix(sudo:session): session closed for user root
Dec 12 23:20:15 volumio sudo[4350]: pam_unix(sudo:session): session closed for user root
Dec 12 23:20:15 volumio volumio[1087]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.230 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Dec 12 23:20:15 volumio volumio[1087]: info: Received Get System Info
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 12 23:20:15 volumio volumio[1087]: info: Discovery: Getting this device information
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::volumioGetState
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 12 23:20:15 volumio volumio[1087]: info: Retrieving Cloud Streaming UI
Dec 12 23:20:15 volumio volumio[1087]: info: Getting Tidal Cloud Configuration
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 12 23:20:15 volumio volumio-remote-updater[587]: Test mode disabled
Dec 12 23:20:15 volumio volumio-remote-updater[587]: Alpha mode disabled
Dec 12 23:20:15 volumio volumio-remote-updater[587]: Alpha legacy test mode disabled
Dec 12 23:20:15 volumio volumio[1087]: info: Getting Qobuz Cloud Configuration
Dec 12 23:20:15 volumio volumio[1087]: info: Asking plugin for UI Config
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 12 23:20:15 volumio volumio[1087]: info: Getting Spotify Cloud Configuration
Dec 12 23:20:15 volumio volumio[1087]: info: Asking plugin for UI Config
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 12 23:20:15 volumio volumio[1087]: info: Saving Spotify Acccount
Dec 12 23:20:15 volumio volumio[1087]: info: Got Tidal Cloud Configuration
Dec 12 23:20:15 volumio volumio[1087]: info: Got it
Dec 12 23:20:15 volumio volumio[1087]: info: Got it
Dec 12 23:20:15 volumio volumio[1087]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 12 23:20:15 volumio volumio[1087]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave')
Dec 12 23:20:15 volumio volumio[1087]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"ANNOUNCEMENT
\n\n- Production line
\n
\nFIX
\n\n- Nodejs 20.5.1 version pinning with static package fallback
\n
\n","title":"Update v4.073","updateavailable":true}
Dec 12 23:20:15 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 12 23:20:16 volumio volumio[1087]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Dec 12 23:20:16 volumio volumio[1087]: info: Loading i18n strings for locale fr
Dec 12 23:20:16 volumio volumio[1087]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 12 23:20:16 volumio volumio[1087]: TypeError: Cannot read properties of null (reading 'logger')
Dec 12 23:20:16 volumio volumio[1087]: at NowPlayingContext.getLogger (/data/plugins/user_interface/now_playing/dist/lib/NowPlayingContext.js:72:83)
Dec 12 23:20:16 volumio volumio[1087]: at ControllerNowPlaying._ControllerNowPlaying_onVolumioLanguageChanged (/data/plugins/user_interface/now_playing/dist/index.js:2038:33)
Dec 12 23:20:16 volumio volumio[1087]: at /volumio/node_modules/v-conf/index.js:166:13
Dec 12 23:20:16 volumio volumio[1087]: at /volumio/node_modules/multimap/index.js:140:9
Dec 12 23:20:16 volumio volumio[1087]: at Array.forEach ()
Dec 12 23:20:16 volumio volumio[1087]: at /volumio/node_modules/multimap/index.js:139:13
Dec 12 23:20:16 volumio volumio[1087]: at mapEach (/volumio/node_modules/multimap/index.js:9:5)
Dec 12 23:20:16 volumio volumio[1087]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)
Dec 12 23:20:16 volumio volumio[1087]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)
Dec 12 23:20:16 volumio volumio[1087]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20)
Dec 12 23:20:16 volumio volumio[1087]: at volumioAppearance.setLanguage (/volumio/app/plugins/miscellanea/appearance/index.js:326:35)
Dec 12 23:20:16 volumio volumio[1087]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32)
Dec 12 23:20:16 volumio volumio[1087]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1685:37)
Dec 12 23:20:16 volumio volumio[1087]: at Socket.emit (node:events:514:28)
Dec 12 23:20:16 volumio volumio[1087]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Dec 12 23:20:16 volumio volumio[1087]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11)
Dec 12 23:20:16 volumio volumio[1087]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 12 23:20:19 volumio sudo[4391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-12 23:19'
Dec 12 23:20:19 volumio sudo[4391]: 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025"
VOLUMIO_VERSION="4.069"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"