-- Logs begin at Mon 2026-04-13 16:20:31 CST, end at Mon 2026-04-13 16:39:04 CST. --
Apr 13 16:38:00 volumio volumio[946]: info: CoreCommandRouter::Close All Modals sent
Apr 13 16:38:08 volumio volumio[946]: info: CoreCommandRouter::Close All Modals sent
Apr 13 16:38:10 volumio volumio-remote-updater[384]: No test mode
Apr 13 16:38:10 volumio volumio-remote-updater[384]: No alpha test mode
Apr 13 16:38:11 volumio volumio[946]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for BLE notification issues
\n- Fix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\n- Update Tidal SDK
\n- Update Qobuz Connect SDK
\n
\n","title":"Update v3.912","updateavailable":true}
Apr 13 16:38:11 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 13 16:38:13 volumio volumio[946]: info: Update: [object Object]
Apr 13 16:38:13 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkSystemIntegrity
Apr 13 16:38:13 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 13 16:38:16 volumio volumio[946]: info: CoreCommandRouter::Close All Modals sent
Apr 13 16:38:18 volumio volumio[946]: info: CoreCommandRouter::Close All Modals sent
Apr 13 16:38:31 volumio volumio[946]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B4%8B%E6%BE%9C%E4%B8%80/b7653504-4f8c-416d-b64b-aec096a22631.jpg'
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 13 16:38:33 volumio volumio[946]: info: Retrieving Cloud Streaming UI
Apr 13 16:38:33 volumio volumio[946]: info: Getting Tidal Cloud Configuration
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 13 16:38:33 volumio volumio[946]: info: Getting Qobuz Cloud Configuration
Apr 13 16:38:33 volumio volumio[946]: info: Asking plugin for UI Config
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 13 16:38:33 volumio volumio[946]: info: Getting Spotify Cloud Configuration
Apr 13 16:38:33 volumio volumio[946]: info: Asking plugin for UI Config
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 13 16:38:33 volumio volumio[946]: info: Saving Spotify Acccount
Apr 13 16:38:33 volumio volumio[946]: info: Got Tidal Cloud Configuration
Apr 13 16:38:33 volumio volumio[946]: info: Got it
Apr 13 16:38:33 volumio volumio[946]: info: Got it
Apr 13 16:38:33 volumio volumio[946]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 13 16:38:33 volumio volumio[946]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 13 16:38:33 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 13 16:38:34 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Apr 13 16:38:37 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 13 16:38:41 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 13 16:38:45 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 13 16:38:48 volumio volumio[946]: info: CoreCommandRouter::volumioGetState
Apr 13 16:39:02 volumio volumio[946]: info: CoreCommandRouter::volumioNext
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::next
Apr 13 16:39:02 volumio volumio[946]: info: ControllerMpd::next
Apr 13 16:39:02 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand next
Apr 13 16:39:02 volumio volumio[946]: info:
Apr 13 16:39:02 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:02 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:02 volumio volumio[946]: info:
Apr 13 16:39:02 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:02 volumio volumio[946]: info: sendMpdCommand next took 10 milliseconds
Apr 13 16:39:02 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:02 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:02 volumio volumio[946]: info:
Apr 13 16:39:02 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:02 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:02 volumio volumio[946]: info:
Apr 13 16:39:02 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:02 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:02 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:02 volumio volumio[946]: info:
Apr 13 16:39:02 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:02 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:02 volumio volumio[946]: info:
Apr 13 16:39:02 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:02 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:02 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:02 volumio volumio[946]: info:
Apr 13 16:39:02 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:02 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:02 volumio volumio[946]: info:
Apr 13 16:39:02 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:02 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:02 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:02 volumio volumio[946]: error: updateQueue error: null
Apr 13 16:39:02 volumio volumio[946]: info: ------------------------------ 23ms
Apr 13 16:39:02 volumio volumio[946]: info: sendMpdCommand status took 20 milliseconds
Apr 13 16:39:02 volumio volumio[946]: info: ------------------------------ 17ms
Apr 13 16:39:02 volumio volumio[946]: info: sendMpdCommand status took 14 milliseconds
Apr 13 16:39:02 volumio volumio[946]: info: ------------------------------ 13ms
Apr 13 16:39:02 volumio volumio[946]: info: sendMpdCommand status took 11 milliseconds
Apr 13 16:39:02 volumio volumio[946]: info: ------------------------------ 10ms
Apr 13 16:39:02 volumio volumio[946]: info: sendMpdCommand status took 8 milliseconds
Apr 13 16:39:02 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:02 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:02 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:02 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:02 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:02 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 13 16:39:02 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:02 volumio volumio[946]: info: CorePlayQueue::getTrack 0
Apr 13 16:39:02 volumio volumio[946]: 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}
Apr 13 16:39:02 volumio volumio[946]: verbose: CURRENT POSITION 0
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::play index undefined
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:02 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:02 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 13 16:39:02 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:02 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:02 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:02 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:02 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:02 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:02 volumio volumio[946]: 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}
Apr 13 16:39:02 volumio volumio[946]: verbose: CURRENT POSITION 1
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop
Apr 13 16:39:02 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:02 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:02 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:02 volumio volumio[946]: info: No code
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: 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}
Apr 13 16:39:03 volumio volumio[946]: verbose: CURRENT POSITION 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: No code
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: 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}
Apr 13 16:39:03 volumio volumio[946]: verbose: CURRENT POSITION 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: No code
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::startPlaybackTimer
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: [1776069543070] ControllerUPNPBrowser::clearAddPlayTrack
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 126ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 122ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 121ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 118ms
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand stop took 24 milliseconds
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand clear
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:03 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand clear took 80 milliseconds
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.6:8200/MediaItems/3436.wav"
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:03 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:03 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:03 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:03 volumio volumio[946]: error: updateQueue error: null
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 16ms
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.6:8200/MediaItems/3436.wav"
Apr 13 16:39:03 volumio volumio[946]: error: updateQueue error: null
Apr 13 16:39:03 volumio volumio[946]: error: updateQueue error: null
Apr 13 16:39:03 volumio volumio[946]: error: updateQueue error: null
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 70ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 67ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 65ms
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:03 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand add "http://192.168.1.6:8200/MediaItems/3436.wav" took 4 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand play
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:03 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:03 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces system playlist update
Apr 13 16:39:03 volumio volumio[946]: info: Ignoring MPD Status Update
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 7ms
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand play took 6 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 6ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 13ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 12ms
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:03 volumio volumio[946]: info:
Apr 13 16:39:03 volumio volumio[946]: ---------------------------- MPD announces state update: player
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand status took 69 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand status took 65 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::getState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand status took 65 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand status took 62 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand status took 53 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand status took 45 milliseconds
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand status took 55 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand status took 44 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand playlistinfo took 18 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand playlistinfo took 18 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand playlistinfo took 12 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand playlistinfo took 12 milliseconds
Apr 13 16:39:03 volumio volumio[946]: info: sendMpdCommand playlistinfo took 11 milliseconds
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::parseState
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 13 16:39:03 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3436.wav","artist":null,"album":null,"uri":"http://192.168.1.6:8200/MediaItems/3436.wav","trackType":"wav"}
Apr 13 16:39:03 volumio volumio[946]: verbose: CURRENT POSITION 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState stateService play
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3436.wav","artist":null,"album":null,"uri":"http://192.168.1.6:8200/MediaItems/3436.wav","trackType":"wav"}
Apr 13 16:39:03 volumio volumio[946]: verbose: CURRENT POSITION 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState stateService play
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play
Apr 13 16:39:03 volumio volumio[946]: info: Received an update from plugin. extracting info from payload
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3436.wav","artist":null,"album":null,"uri":"http://192.168.1.6:8200/MediaItems/3436.wav","trackType":"wav"}
Apr 13 16:39:03 volumio volumio[946]: verbose: CURRENT POSITION 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState stateService play
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play
Apr 13 16:39:03 volumio volumio[946]: info: Received an update from plugin. extracting info from payload
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3436.wav","artist":null,"album":null,"uri":"http://192.168.1.6:8200/MediaItems/3436.wav","trackType":"wav"}
Apr 13 16:39:03 volumio volumio[946]: verbose: CURRENT POSITION 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState stateService play
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play
Apr 13 16:39:03 volumio volumio[946]: info: Received an update from plugin. extracting info from payload
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3436.wav","artist":null,"album":null,"uri":"http://192.168.1.6:8200/MediaItems/3436.wav","trackType":"wav"}
Apr 13 16:39:03 volumio volumio[946]: verbose: CURRENT POSITION 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState stateService play
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play
Apr 13 16:39:03 volumio volumio[946]: info: Received an update from plugin. extracting info from payload
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: ControllerMpd::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::servicePushState
Apr 13 16:39:03 volumio volumio[946]: info: CorePlayQueue::getTrack 1
Apr 13 16:39:03 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3436.wav","artist":null,"album":null,"uri":"http://192.168.1.6:8200/MediaItems/3436.wav","trackType":"wav"}
Apr 13 16:39:03 volumio volumio[946]: verbose: CURRENT POSITION 1
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState stateService play
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play
Apr 13 16:39:03 volumio volumio[946]: info: Received an update from plugin. extracting info from payload
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreStateMachine::pushState
Apr 13 16:39:03 volumio volumio[946]: info: CoreCommandRouter::volumioPushState
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 211ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 265ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 245ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 243ms
Apr 13 16:39:03 volumio volumio[946]: info: ------------------------------ 236ms
Apr 13 16:39:03 volumio volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 13 16:39:03 volumio volumio[946]: Error: connect ETIMEDOUT 108.160.169.186:80
Apr 13 16:39:03 volumio volumio[946]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
Apr 13 16:39:03 volumio volumio[946]: errno: -110,
Apr 13 16:39:03 volumio volumio[946]: code: 'ETIMEDOUT',
Apr 13 16:39:03 volumio volumio[946]: syscall: 'connect',
Apr 13 16:39:03 volumio volumio[946]: address: '108.160.169.186',
Apr 13 16:39:03 volumio volumio[946]: port: 80
Apr 13 16:39:03 volumio volumio[946]: }
Apr 13 16:39:03 volumio volumio[946]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 13 16:39:04 volumio sudo[6362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-13 16:38
Apr 13 16:39:04 volumio sudo[6362]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d"
VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee"
VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623"
VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST"
VOLUMIO_VERSION="3.661"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"