-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2025-12-17 09:00:36 CET. --
Dec 17 08:59:01 volumio volumio[5245]: info: Command Router : Notfying DB Updatefalse
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::Close All Modals sent
Dec 17 08:59:01 volumio volumio[5245]: verbose: ControllerMpd::parseState
Dec 17 08:59:01 volumio volumio[5245]: info: ControllerMpd::pushState
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::servicePushState
Dec 17 08:59:01 volumio volumio[5245]: info: CoreStateMachine::pushState
Dec 17 08:59:01 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::volumioPushState
Dec 17 08:59:01 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:01 volumio volumio[5245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd
Dec 17 08:59:01 volumio volumio[5245]: info: ControllerMpd::pushState
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::servicePushState
Dec 17 08:59:01 volumio volumio[5245]: info: CoreStateMachine::pushState
Dec 17 08:59:01 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::volumioPushState
Dec 17 08:59:01 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:01 volumio volumio[5245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd
Dec 17 08:59:01 volumio volumio[5245]: info: ControllerMpd::pushState
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::servicePushState
Dec 17 08:59:01 volumio volumio[5245]: info: CoreStateMachine::pushState
Dec 17 08:59:01 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::volumioPushState
Dec 17 08:59:01 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:01 volumio volumio[5245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd
Dec 17 08:59:01 volumio volumio[5245]: info: ControllerMpd::pushState
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::servicePushState
Dec 17 08:59:01 volumio volumio[5245]: info: CoreStateMachine::pushState
Dec 17 08:59:01 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::volumioPushState
Dec 17 08:59:01 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:01 volumio volumio[5245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd
Dec 17 08:59:01 volumio volumio[5245]: info: ------------------------------ 3096ms
Dec 17 08:59:01 volumio volumio[5245]: info: ------------------------------ 3043ms
Dec 17 08:59:01 volumio volumio[5245]: info: ------------------------------ 3044ms
Dec 17 08:59:01 volumio volumio[5245]: info: ------------------------------ 3043ms
Dec 17 08:59:01 volumio go-librespot[5520]: time="2025-12-17T08:59:01+01:00" level=debug msg="new websocket client"
Dec 17 08:59:01 volumio volumio[5245]: info: Connection to go-librespot Websocket established
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 17 08:59:01 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 17 08:59:01 volumio volumio[5245]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 17 08:59:01 volumio volumio[5245]: info: Adding plugin multiroom to MyMusic Plugins
Dec 17 08:59:01 volumio volumio[5245]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 17 08:59:01 volumio volumio[5245]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 17 08:59:01 volumio volumio[5245]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 17 08:59:01 volumio volumio[5245]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 17 08:59:01 volumio volumio[5245]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 17 08:59:01 volumio volumio[5245]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 17 08:59:02 volumio volumio[5245]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 17 08:59:02 volumio volumio[5245]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 17 08:59:02 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 17 08:59:02 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 17 08:59:02 volumio volumio[5245]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 17 08:59:02 volumio volumio[5245]: info: MyVolumio not started
Dec 17 08:59:02 volumio volumio[5245]: info: Initializing device activation check
Dec 17 08:59:02 volumio volumio[5245]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 17 08:59:02 volumio volumio[5245]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 17 08:59:02 volumio volumio[5245]: info: Starting Streaming Service Transparent Proxy
Dec 17 08:59:02 volumio volumio[5245]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 17 08:59:02 volumio volumio[5245]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 17 08:59:02 volumio volumio[5245]: info: Streaming services startup
Dec 17 08:59:02 volumio volumio[5245]: info: Starting Streaming Daemon
Dec 17 08:59:02 volumio sudo[5637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 17 08:59:02 volumio sudo[5637]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 17 08:59:02 volumio sudo[5637]: pam_unix(sudo:session): session closed for user root
Dec 17 08:59:02 volumio volumio[5245]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 17 08:59:02 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Dec 17 08:59:02 volumio volumio[5245]: error: Cannot start Volumio Streaming Daemon
Dec 17 08:59:02 volumio volumio[5245]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 17 08:59:02 volumio volumio[5245]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 17 08:59:02 volumio volumio[5245]: STREAMING PROXY: Starting server on port 3245
Dec 17 08:59:02 volumio volumio[5245]: Node JS runtime: 14
Dec 17 08:59:02 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 17 08:59:04 volumio volumio[5245]: info: Getting Spotify volume
Dec 17 08:59:04 volumio volumio[5245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Dec 17 08:59:04 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:04 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:04 volumio volumio[5245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Dec 17 08:59:04 volumio volumio[5245]: SPOTIFY: SPOTIFY VOLUME undefined
Dec 17 08:59:04 volumio volumio[5245]: SPOTIFY: VOLUMIO VOLUME 100
Dec 17 08:59:04 volumio volumio[5245]: info: Aligning Spotify Volume to Volumio Volume
Dec 17 08:59:04 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:04 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:04 volumio volumio[5245]: info: Setting Spotify Volume from Volumio: 100
Dec 17 08:59:04 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Dec 17 08:59:04 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Dec 17 08:59:05 volumio volumio[5245]: SPOTIFY: SETTING SPOTIFY VOLUME 100
Dec 17 08:59:05 volumio volumio[5245]: info: Sending Spotify command with payload to local API: /player/volume
Dec 17 08:59:06 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 17 08:59:11 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:11 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:16 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:16 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:17 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 17 08:59:17 volumio volumio[5245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Dec 17 08:59:17 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:17 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:21 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 17 08:59:21 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 17 08:59:21 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 17 08:59:21 volumio volumio-remote-updater[744]: No test mode
Dec 17 08:59:21 volumio volumio-remote-updater[744]: No alpha test mode
Dec 17 08:59:21 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 17 08:59:21 volumio volumio[5245]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for TIDAL Authentication refresh
\n
\nNEW ADDITIONS
\n\n- Update Bluez to 5.83
\n
\n","title":"Update v3.874","updateavailable":true}
Dec 17 08:59:21 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 17 08:59:22 volumio volumio[5245]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 17 08:59:22 volumio volumio[5245]: info: Completed starting MyVolumio Plugin
Dec 17 08:59:22 volumio volumio[5245]: [Metrics] CommandRouter: 62s 12.50ms
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::Close All Modals sent
Dec 17 08:59:22 volumio volumio[5245]: info: CoreCommandRouter::Close All Modals sent
Dec 17 08:59:22 volumio volumio[5245]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Dec 17 08:59:22 volumio volumio[5245]: info: FusionDsp - ---- read samplerate from file: 44100
Dec 17 08:59:22 volumio volumio[5245]: info: camilladsp stopping service pid 5580...
Dec 17 08:59:22 volumio volumio[5245]: info: camilladsp service terminated, instance 1
Dec 17 08:59:22 volumio volumio[5245]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Dec 17 08:59:22 volumio volumio[5245]: info: FusionDsp - Effects disabled
Dec 17 08:59:22 volumio volumio[5245]: info: camilladsp service started and running in background, instance 1
Dec 17 08:59:22 volumio volumio[5245]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Dec 17 08:59:22 volumio volumio[5245]: info: FusionDsp - ---- read samplerate from file: 44100
Dec 17 08:59:22 volumio volumio[5245]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Dec 17 08:59:22 volumio volumio[5245]: info: FusionDsp - Effects disabled
Dec 17 08:59:22 volumio volumio[5245]: error: FusionDsp - WebSocket error: [object Object]
Dec 17 08:59:22 volumio volumio[5245]: info: camilladsp spawned new process with pid 5686, instance 1, run: true
Dec 17 08:59:23 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 17 08:59:23 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 17 08:59:23 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 17 08:59:25 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:25 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:27 volumio volumio[5245]: info: camilladsp spawned new process with pid 5694, instance 1, run: true
Dec 17 08:59:29 volumio volumio[5245]: info: BOOT COMPLETED
Dec 17 08:59:35 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:35 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:42 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:42 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:47 volumio volumio[5245]: info: CoreCommandRouter::volumioClearQueue
Dec 17 08:59:47 volumio volumio[5245]: info: CoreStateMachine::ClearQueue
Dec 17 08:59:47 volumio volumio[5245]: info: CoreStateMachine::stop
Dec 17 08:59:47 volumio volumio[5245]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 17 08:59:47 volumio volumio[5245]: info: CorePlayQueue::clearPlayQueue
Dec 17 08:59:47 volumio volumio[5245]: info: CorePlayQueue::saveQueue
Dec 17 08:59:47 volumio volumio[5245]: info: CoreCommandRouter::volumioPushState
Dec 17 08:59:47 volumio volumio[5245]: info: CoreCommandRouter::volumioPushQueue
Dec 17 08:59:47 volumio volumio[5245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Dec 17 08:59:50 volumio volumio[5245]: info: CoreCommandRouter::volumioRandom
Dec 17 08:59:50 volumio volumio[5245]: info: CoreCommandRouter::writePlayerControls
Dec 17 08:59:50 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:50 volumio volumio[5245]: info: CoreStateMachine::setRepeat true single false
Dec 17 08:59:50 volumio volumio[5245]: info: CoreStateMachine::pushState
Dec 17 08:59:50 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:50 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 17 08:59:50 volumio volumio[5245]: info: CoreCommandRouter::volumioPushState
Dec 17 08:59:50 volumio volumio[5245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Dec 17 08:59:51 volumio volumio[5245]: info: UPDATER: Scheduling automatic update
Dec 17 08:59:51 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 17 08:59:51 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 17 08:59:51 volumio volumio[5245]: info: UPDATER: Auto update will take place at: Thu Dec 18 2025 05:26:39 GMT+0100 (Central European Standard Time)
Dec 17 08:59:52 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:52 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:59 volumio volumio[5245]: verbose: New Socket.io Connection to 192.168.2.127 from 192.168.2.186 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:59 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:59 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::volumioGetQueue
Dec 17 08:59:59 volumio volumio[5245]: info: CoreStateMachine::getQueue
Dec 17 08:59:59 volumio volumio[5245]: info: CorePlayQueue::getQueue
Dec 17 08:59:59 volumio volumio[5245]: info: Listing playlists
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 17 08:59:59 volumio volumio[5245]: info: Received Get System Info
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 17 08:59:59 volumio volumio[5245]: info: Discovery: Getting this device information
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:59 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 08:59:59 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 17 08:59:59 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 17 09:00:10 volumio volumio[5245]: info: Getting Alsa Cards List without I2S DAC
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Dec 17 09:00:10 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Dec 17 09:00:17 volumio volumio[5245]: info: CoreCommandRouter::volumioGetState
Dec 17 09:00:17 volumio volumio[5245]: info: CorePlayQueue::getTrack 0
Dec 17 09:00:18 volumio volumio-remote-updater[744]: No test mode
Dec 17 09:00:18 volumio volumio-remote-updater[744]: No alpha test mode
Dec 17 09:00:18 volumio volumio[5245]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for TIDAL Authentication refresh
\n
\nNEW ADDITIONS
\n\n- Update Bluez to 5.83
\n
\n","title":"Update v3.874","updateavailable":true}
Dec 17 09:00:18 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 17 09:00:21 volumio volumio[5245]: info: Update: [object Object]
Dec 17 09:00:21 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkSystemIntegrity
Dec 17 09:00:21 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 17 09:00:23 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem
Dec 17 09:00:23 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates
Dec 17 09:00:23 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 17 09:00:23 volumio volumio-remote-updater[744]: PROGRESS: 0, STATUS: "Starting update", ETA: "6m"
Dec 17 09:00:23 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: updater_comm , notifyProgress
Dec 17 09:00:23 volumio volumio-remote-updater[744]: /bin/rm: cannot remove '/imgpart/rck*': No such file or directory
Dec 17 09:00:23 volumio volumio-remote-updater[744]: /bin/rm: cannot remove '/imgpart/*.part': No such file or directory
Dec 17 09:00:23 volumio volumio-remote-updater[744]: /bin/rm: cannot remove '/imgpart/*.zs-old': No such file or directory
Dec 17 09:00:23 volumio volumio-remote-updater[744]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Dec 17 09:00:23 volumio volumio[5245]: info: Killing processes that might interfere with OTA Updates
Dec 17 09:00:23 volumio sudo[5869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-keyboard
Dec 17 09:00:23 volumio sudo[5869]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 17 09:00:23 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:23 volumio volumio[5245]: updateProgress
Dec 17 09:00:23 volumio sudo[5869]: pam_unix(sudo:session): session closed for user root
Dec 17 09:00:23 volumio sudo[5872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-window-manager
Dec 17 09:00:23 volumio sudo[5872]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 17 09:00:23 volumio volumio[5245]: {
Dec 17 09:00:23 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:23 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:23 volumio volumio[5245]: progress: 5,
Dec 17 09:00:23 volumio volumio[5245]: status: 'Příprava aktualizace'
Dec 17 09:00:23 volumio volumio[5245]: }
Dec 17 09:00:23 volumio volumio[5245]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-keyboard
Dec 17 09:00:23 volumio volumio[5245]: matchbox-keyboard: no process found
Dec 17 09:00:23 volumio sudo[5872]: pam_unix(sudo:session): session closed for user root
Dec 17 09:00:23 volumio volumio[5245]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-window-manager
Dec 17 09:00:23 volumio volumio[5245]: matchbox-window-manager: no process found
Dec 17 09:00:23 volumio volumio-remote-updater[744]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Dec 17 09:00:23 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:23 volumio volumio[5245]: updateProgress
Dec 17 09:00:23 volumio volumio[5245]: {
Dec 17 09:00:23 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:23 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:23 volumio volumio[5245]: progress: 5,
Dec 17 09:00:23 volumio volumio[5245]: status: 'Příprava aktualizace'
Dec 17 09:00:23 volumio volumio[5245]: }
Dec 17 09:00:24 volumio volumio-remote-updater[744]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:24 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:24 volumio volumio[5245]: updateProgress
Dec 17 09:00:24 volumio volumio[5245]: {
Dec 17 09:00:24 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:24 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:24 volumio volumio[5245]: progress: 10,
Dec 17 09:00:24 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:24 volumio volumio[5245]: }
Dec 17 09:00:24 volumio volumio-remote-updater[744]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:24 volumio volumio-remote-updater[744]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:24 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:24 volumio volumio-remote-updater[744]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:24 volumio volumio-remote-updater[744]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:24 volumio volumio[5245]: updateProgress
Dec 17 09:00:24 volumio volumio-remote-updater[744]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:24 volumio volumio[5245]: {
Dec 17 09:00:24 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:24 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:24 volumio volumio[5245]: progress: 10,
Dec 17 09:00:24 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:24 volumio volumio[5245]: }
Dec 17 09:00:24 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:24 volumio volumio[5245]: updateProgress
Dec 17 09:00:24 volumio volumio[5245]: {
Dec 17 09:00:24 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:24 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:24 volumio volumio[5245]: progress: 10,
Dec 17 09:00:24 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:24 volumio volumio[5245]: }
Dec 17 09:00:24 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:24 volumio volumio[5245]: updateProgress
Dec 17 09:00:24 volumio volumio[5245]: {
Dec 17 09:00:24 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:24 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:24 volumio volumio[5245]: progress: 10,
Dec 17 09:00:24 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:24 volumio volumio[5245]: }
Dec 17 09:00:25 volumio volumio-remote-updater[744]: PROGRESS: 10, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:25 volumio volumio-remote-updater[744]: PROGRESS: 12, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:25 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:25 volumio volumio[5245]: updateProgress
Dec 17 09:00:25 volumio volumio[5245]: {
Dec 17 09:00:25 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:25 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:25 volumio volumio[5245]: progress: 12,
Dec 17 09:00:25 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:25 volumio volumio[5245]: }
Dec 17 09:00:26 volumio volumio-remote-updater[744]: PROGRESS: 12, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:26 volumio volumio-remote-updater[744]: PROGRESS: 15, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:26 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:26 volumio volumio[5245]: updateProgress
Dec 17 09:00:26 volumio volumio[5245]: {
Dec 17 09:00:26 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:26 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:26 volumio volumio[5245]: progress: 15,
Dec 17 09:00:26 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:26 volumio volumio[5245]: }
Dec 17 09:00:26 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:26 volumio volumio[5245]: updateProgress
Dec 17 09:00:26 volumio volumio[5245]: {
Dec 17 09:00:26 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:26 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:26 volumio volumio[5245]: progress: 15,
Dec 17 09:00:26 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:26 volumio volumio[5245]: }
Dec 17 09:00:27 volumio volumio-remote-updater[744]: PROGRESS: 15, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:27 volumio volumio-remote-updater[744]: PROGRESS: 18, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:27 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:27 volumio volumio[5245]: updateProgress
Dec 17 09:00:27 volumio volumio[5245]: {
Dec 17 09:00:27 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:27 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:27 volumio volumio[5245]: progress: 18,
Dec 17 09:00:27 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:27 volumio volumio[5245]: }
Dec 17 09:00:28 volumio volumio-remote-updater[744]: PROGRESS: 18, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:28 volumio volumio-remote-updater[744]: PROGRESS: 21, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:28 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:28 volumio volumio[5245]: updateProgress
Dec 17 09:00:28 volumio volumio[5245]: {
Dec 17 09:00:28 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:28 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:28 volumio volumio[5245]: progress: 21,
Dec 17 09:00:28 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:28 volumio volumio[5245]: }
Dec 17 09:00:28 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:28 volumio volumio[5245]: updateProgress
Dec 17 09:00:28 volumio volumio[5245]: {
Dec 17 09:00:28 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:28 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:28 volumio volumio[5245]: progress: 21,
Dec 17 09:00:28 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:28 volumio volumio[5245]: }
Dec 17 09:00:29 volumio volumio-remote-updater[744]: PROGRESS: 21, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:29 volumio volumio-remote-updater[744]: PROGRESS: 25, STATUS: "Creating backup", ETA: "5m"
Dec 17 09:00:29 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:29 volumio volumio[5245]: updateProgress
Dec 17 09:00:29 volumio volumio[5245]: {
Dec 17 09:00:29 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:29 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:29 volumio volumio[5245]: progress: 25,
Dec 17 09:00:29 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:29 volumio volumio[5245]: }
Dec 17 09:00:29 volumio volumio[5245]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Dec 17 09:00:29 volumio volumio[5245]: updateProgress
Dec 17 09:00:29 volumio volumio[5245]: {
Dec 17 09:00:29 volumio volumio[5245]: downloadSpeed: '',
Dec 17 09:00:29 volumio volumio[5245]: eta: '5m',
Dec 17 09:00:29 volumio volumio[5245]: progress: 25,
Dec 17 09:00:29 volumio volumio[5245]: status: 'Vytvoření zálohy'
Dec 17 09:00:29 volumio volumio[5245]: }
Dec 17 09:00:31 volumio sudo[5296]: pam_unix(sudo:session): session closed for user root
Dec 17 09:00:31 volumio volumio[5245]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds
Dec 17 09:00:36 volumio volumio[5245]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 17 09:00:36 volumio volumio[5245]: TypeError: Cannot read property 'replace' of undefined
Dec 17 09:00:36 volumio volumio[5245]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28)
Dec 17 09:00:36 volumio volumio[5245]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20)
Dec 17 09:00:36 volumio volumio[5245]: at listOnTimeout (internal/timers.js:554:17)
Dec 17 09:00:36 volumio volumio[5245]: at processTimers (internal/timers.js:497:7)
Dec 17 09:00:36 volumio volumio[5245]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 17 09:00:36 volumio sudo[5957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-17 08:59
Dec 17 09:00:36 volumio sudo[5957]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="570c5791513f5bac7da274aba6690c1a961705de"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST"
VOLUMIO_VERSION="3.812"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"