-- Logs begin at Wed 2025-01-08 12:40:32 CET, end at Fri 2025-02-28 17:28:23 CET. --
Feb 28 17:27:01 volumio volumio[997]: info:
Feb 28 17:27:01 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:01 volumio volumio[997]: info:
Feb 28 17:27:01 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:01 volumio volumio[997]: info:
Feb 28 17:27:01 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:01 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand status took 29 milliseconds
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:01 volumio volumio[997]: info:
Feb 28 17:27:01 volumio volumio[997]: ---------------------------- MPD announces system playlist update
Feb 28 17:27:01 volumio volumio[997]: info: Ignoring MPD Status Update
Feb 28 17:27:01 volumio volumio[997]: info:
Feb 28 17:27:01 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand status took 29 milliseconds
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand status took 29 milliseconds
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand playlistinfo took 2 milliseconds
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CTHiRes","artist":"CITY HIRES","album":null,"uri":"https://plus.onair.network:8020/CTHiRes","trackType":""}
Feb 28 17:27:01 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus stop
Feb 28 17:27:01 volumio volumio[997]: info: ------------------------------ 32ms
Feb 28 17:27:01 volumio volumio[997]: info:
Feb 28 17:27:01 volumio volumio[997]: ---------------------------- MPD announces system playlist update
Feb 28 17:27:01 volumio volumio[997]: info: Ignoring MPD Status Update
Feb 28 17:27:01 volumio volumio[997]: info:
Feb 28 17:27:01 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:01 volumio volumio[997]: info:
Feb 28 17:27:01 volumio volumio[997]: ---------------------------- MPD announces system playlist update
Feb 28 17:27:01 volumio volumio[997]: info: Ignoring MPD Status Update
Feb 28 17:27:01 volumio volumio[997]: info:
Feb 28 17:27:01 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:01 volumio volumio[997]: info: ------------------------------ 5ms
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand status took 5 milliseconds
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand playlistinfo took 4 milliseconds
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand playlistinfo took 4 milliseconds
Feb 28 17:27:01 volumio volumio[997]: info: ------------------------------ 2ms
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand status took 2 milliseconds
Feb 28 17:27:01 volumio volumio[997]: info: ------------------------------ 3ms
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand status took 2 milliseconds
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"CTHiRes","artist":"CITY HIRES","album":null,"uri":"https://plus.onair.network:8020/CTHiRes","trackType":""}
Feb 28 17:27:01 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:01 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"CTHiRes","artist":"CITY HIRES","album":null,"uri":"https://plus.onair.network:8020/CTHiRes","trackType":""}
Feb 28 17:27:01 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:01 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: ------------------------------ 60ms
Feb 28 17:27:01 volumio volumio[997]: info: ------------------------------ 60ms
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand playlistinfo took 28 milliseconds
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand playlistinfo took 28 milliseconds
Feb 28 17:27:01 volumio volumio[997]: info: sendMpdCommand playlistinfo took 28 milliseconds
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:01 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"CTHiRes","artist":"CITY HIRES","album":null,"uri":"https://plus.onair.network:8020/CTHiRes","trackType":""}
Feb 28 17:27:01 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:01 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"CTHiRes","artist":"CITY HIRES","album":null,"uri":"https://plus.onair.network:8020/CTHiRes","trackType":""}
Feb 28 17:27:01 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:01 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":274,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"264 Kbps","isStreaming":false,"title":"CTHiRes","artist":"CITY HIRES","album":null,"uri":"https://plus.onair.network:8020/CTHiRes","trackType":""}
Feb 28 17:27:01 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:01 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:01 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:01 volumio volumio[997]: info: ------------------------------ 73ms
Feb 28 17:27:01 volumio volumio[997]: info: ------------------------------ 71ms
Feb 28 17:27:01 volumio volumio[997]: info: ------------------------------ 71ms
Feb 28 17:27:10 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 28 17:27:10 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 28 17:27:10 volumio volumio[997]: info: Discovery: Getting this device information
Feb 28 17:27:10 volumio volumio[997]: info: CoreCommandRouter::volumioGetState
Feb 28 17:27:10 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 28 17:27:14 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 28 17:27:14 volumio volumio[997]: info: CURURI: artists://
Feb 28 17:27:14 volumio volumio[997]: info: Preload queue cleared
Feb 28 17:27:29 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 28 17:27:29 volumio volumio[997]: info: CURURI: artists://BluVinil
Feb 28 17:27:29 volumio volumio[997]: info: Preload queue cleared
Feb 28 17:27:29 volumio volumio[997]: info: Preload queue cleared
Feb 28 17:27:29 volumio volumio[997]: info: Preloading song: music-library/USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac
Feb 28 17:27:29 volumio volumio[997]: info: Exploding uri music-library/USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac in service mpd
Feb 28 17:27:29 volumio volumio[997]: info: ALBUMART /albumart?cacheid=662&web=BluVinil/Apa%C5%A1i/mega&path=%2Fmnt%2FUSB%2FKINGSTON%2FDoma%C4%87inskite%2FBluVinil%20-%20Apa%C5%A1i.flac&metadata=false
Feb 28 17:27:29 volumio volumio[997]: info: URI /mnt/USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac
Feb 28 17:27:29 volumio volumio[997]: info: No valid Plugin REST Endpoint
Feb 28 17:27:31 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 28 17:27:31 volumio volumio[997]: info: CURURI: artists://BluVinil/Apa%C5%A1i
Feb 28 17:27:31 volumio volumio[997]: info: Preload queue cleared
Feb 28 17:27:32 volumio volumio[997]: info: No valid Plugin REST Endpoint
Feb 28 17:27:33 volumio volumio[997]: info: Preload queue cleared
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioReplaceandPlayItems
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::ClearQueue
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::stop
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::stPlaybackTimer
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::updateTrackBlock
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrackBlock
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::serviceStop
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::serviceStop
Feb 28 17:27:33 volumio volumio[997]: info: [1740760053489] ControllerWebradio::stop
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand stop
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::clearPlayQueue
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::saveQueue
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushQueue
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::addQueueItems
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::addQueueItems
Feb 28 17:27:33 volumio volumio[997]: info: Preload queue cleared
Feb 28 17:27:33 volumio volumio[997]: info: Adding Item to queue: artists://BluVinil/Apa%C5%A1i
Feb 28 17:27:33 volumio volumio[997]: info: Exploding uri artists://BluVinil/Apa%C5%A1i in service mpd
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushQueue
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::saveQueue
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::updateTrackBlock
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrackBlock
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPlay
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::play index 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::stop
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::play index undefined
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::startPlaybackTimer
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::clearAddPlayTracks USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand stop
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand stop took 18 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand stop took 1 milliseconds
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand clear
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces system playlist update
Feb 28 17:27:33 volumio volumio[997]: info: Ignoring MPD Status Update
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand status took 3 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand clear took 2 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand status took 2 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand status took 1 milliseconds
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac"
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: 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}
Feb 28 17:27:33 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState stateService stop
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus stop
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: No code
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: 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}
Feb 28 17:27:33 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState stateService stop
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus stop
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: No code
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 48ms
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 48ms
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces system playlist update
Feb 28 17:27:33 volumio volumio[997]: info: Ignoring MPD Status Update
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces system playlist update
Feb 28 17:27:33 volumio volumio[997]: info: Ignoring MPD Status Update
Feb 28 17:27:33 volumio volumio[997]: error: updateQueue error: null
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces system playlist update
Feb 28 17:27:33 volumio volumio[997]: info: Ignoring MPD Status Update
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 52ms
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand playlistinfo took 52 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand add "USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac" took 52 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 4ms
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 3ms
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand play
Feb 28 17:27:33 volumio volumio[997]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 57ms
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces system playlist update
Feb 28 17:27:33 volumio volumio[997]: info: Ignoring MPD Status Update
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces system playlist update
Feb 28 17:27:33 volumio volumio[997]: info: Ignoring MPD Status Update
Feb 28 17:27:33 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 27ms
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand play took 26 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 25ms
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 24ms
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand status took 10 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand status took 8 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand status took 9 milliseconds
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:33 volumio volumio[997]: info:
Feb 28 17:27:33 volumio volumio[997]: ---------------------------- MPD announces state update: player
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::getState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand status
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand status took 28 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand playlistinfo took 27 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand playlistinfo took 27 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand playlistinfo took 27 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand status took 25 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand status took 24 milliseconds
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseState
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Apaši","artist":"BluVinil","album":"Apaši","uri":"USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac","trackType":"flac"}
Feb 28 17:27:33 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus stop
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1083 Kbps","isStreaming":false,"title":"Apaši","artist":"BluVinil","album":"Apaši","uri":"USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac","trackType":"flac"}
Feb 28 17:27:33 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:33 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1083 Kbps","isStreaming":false,"title":"Apaši","artist":"BluVinil","album":"Apaši","uri":"USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac","trackType":"flac"}
Feb 28 17:27:33 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:33 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 52ms
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 68ms
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 67ms
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand playlistinfo took 45 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand playlistinfo took 44 milliseconds
Feb 28 17:27:33 volumio volumio[997]: info: sendMpdCommand playlistinfo took 44 milliseconds
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:33 volumio volumio[997]: verbose: ControllerMpd::parseTrackInfo
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":217,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1084 Kbps","isStreaming":false,"title":"Apaši","artist":"BluVinil","album":"Apaši","uri":"USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac","trackType":"flac"}
Feb 28 17:27:33 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:33 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1180 Kbps","isStreaming":false,"title":"Apaši","artist":"BluVinil","album":"Apaši","uri":"USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac","trackType":"flac"}
Feb 28 17:27:33 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:33 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: ControllerMpd::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::servicePushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388,"duration":229,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1106 Kbps","isStreaming":false,"title":"Apaši","artist":"BluVinil","album":"Apaši","uri":"USB/KINGSTON/Domaćinskite/BluVinil - Apaši.flac","trackType":"flac"}
Feb 28 17:27:33 volumio volumio[997]: verbose: CURRENT POSITION 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState stateService play
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::syncState currentStatus play
Feb 28 17:27:33 volumio volumio[997]: info: Received an update from plugin. extracting info from payload
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: CoreStateMachine::pushState
Feb 28 17:27:33 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:33 volumio volumio[997]: info: CoreCommandRouter::volumioPushState
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 103ms
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 101ms
Feb 28 17:27:33 volumio volumio[997]: info: ------------------------------ 100ms
Feb 28 17:27:40 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 28 17:27:40 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 28 17:27:40 volumio volumio[997]: info: Discovery: Getting this device information
Feb 28 17:27:40 volumio volumio[997]: info: CoreCommandRouter::volumioGetState
Feb 28 17:27:40 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:27:40 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 28 17:27:51 volumio kernel: mmc0: Card stuck being busy! __mmc_poll_for_busy
Feb 28 17:27:52 volumio kernel: mmc0: card never left busy state
Feb 28 17:27:52 volumio kernel: mmc0: tried to HW reset card, got error -110
Feb 28 17:27:52 volumio kernel: mmcblk0: recovery failed!
Feb 28 17:27:52 volumio kernel: I/O error, dev mmcblk0, sector 115568712 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2
Feb 28 17:27:52 volumio kernel: Buffer I/O error on dev mmcblk0p3, logical block 55050276, lost async page write
Feb 28 17:27:52 volumio kernel: I/O error, dev mmcblk0, sector 115568766 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2
Feb 28 17:27:52 volumio kernel: Buffer I/O error on dev mmcblk0p3, logical block 55050303, lost async page write
Feb 28 17:27:52 volumio kernel: mmc0: card 0007 removed
Feb 28 17:27:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #13386284: comm nmbd: reading directory lblock 0
Feb 28 17:27:52 volumio nmbd[1736]: [2025/02/28 17:27:52.393971, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database)
Feb 28 17:27:52 volumio nmbd[1736]: wins_write_database: Can't open /var/lib/samba/wins.dat.1736: Input/output error
Feb 28 17:27:52 volumio volumio[997]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:0007/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/F102-DE29 /dev/disk/by-label/boot /dev/disk/by-id/mmc-SD64G_0x00285ab9-part1 /dev/disk/by-partuuid/8ca0658c-01 /dev/disk/by-path/platform-fe340000.mmc-part1","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:0007/block/mmcblk0/mmcblk0p1","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"F102-DE29","ID_FS_UUID_ENC":"F102-DE29","ID_FS_VERSION":"FAT16","ID_NAME":"SD64G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"187500","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"8ca0658c-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x00285ab9","MAJOR":"179","MINOR":"1","PARTN":"1","SEQNUM":"2399","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3669482"}
Feb 28 17:27:52 volumio volumio[997]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:0007/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/volumio /dev/disk/by-path/platform-fe340000.mmc-part2 /dev/disk/by-partuuid/8ca0658c-02 /dev/disk/by-uuid/db7775c5-9234-4b9a-955e-7341a245d498 /dev/disk/by-id/mmc-SD64G_0x00285ab9-part2","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:0007/block/mmcblk0/mmcblk0p2","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_UUID_ENC":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_VERSION":"1.0","ID_NAME":"SD64G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"188416","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"5279744","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"8ca0658c-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x00285ab9","MAJOR":"179","MINOR":"2","PARTN":"2","SEQNUM":"2400","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3661448"}
Feb 28 17:27:52 volumio systemd[1]: Stopped target Local File Systems.
Feb 28 17:27:52 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_check_bdev_write_error:218: comm kworker/u8:2: Error while async write back metadata
Feb 28 17:27:52 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 13387673 starting block 57895436)
Feb 28 17:27:52 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 55161356
Feb 28 17:27:52 volumio kernel: JBD2: Detected IO errors while flushing file data on mmcblk0p3-8
Feb 28 17:27:52 volumio kernel: Aborting journal on device mmcblk0p3-8.
Feb 28 17:27:52 volumio kernel: JBD2: I/O error when updating journal superblock for mmcblk0p3-8.
Feb 28 17:27:52 volumio volumio[997]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:0007/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/4112086d-38d3-4206-a7af-ef93bf4e4008 /dev/disk/by-label/volumio_data /dev/disk/by-id/mmc-SD64G_0x00285ab9-part3 /dev/disk/by-path/platform-fe340000.mmc-part3 /dev/disk/by-partuuid/8ca0658c-03","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:0007/block/mmcblk0/mmcblk0p3","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_UUID_ENC":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_VERSION":"1.0","ID_NAME":"SD64G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"116723247","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"8ca0658c-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x00285ab9","MAJOR":"179","MINOR":"3","PARTN":"3","SEQNUM":"2401","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3661788"}
Feb 28 17:27:52 volumio systemd[1]: Unmounting /boot...
Feb 28 17:27:52 volumio kernel: FAT-fs (mmcblk0p1): unable to read boot sector to mark fs as dirty
Feb 28 17:27:52 volumio systemd[1]: boot.mount: Succeeded.
Feb 28 17:27:52 volumio systemd[1]: Unmounted /boot.
Feb 28 17:27:52 volumio systemd[1]: systemd-fsck@dev-disk-by\x2duuid-F102\x2dDE29.service: Succeeded.
Feb 28 17:27:52 volumio systemd[1]: Stopped File System Check on /dev/disk/by-uuid/F102-DE29.
Feb 28 17:27:53 volumio kernel: mmc0: card never left busy state
Feb 28 17:27:53 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:27:55 volumio kernel: mmc0: card never left busy state
Feb 28 17:27:55 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:27:56 volumio kernel: mmc0: card never left busy state
Feb 28 17:27:56 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:27:57 volumio kernel: mmc0: card never left busy state
Feb 28 17:27:57 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:27:59 volumio kernel: mmc0: card never left busy state
Feb 28 17:27:59 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:00 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:00 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:03 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:03 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:04 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:04 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:05 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:05 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:08 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:08 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:09 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:09 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:10 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 28 17:28:10 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 28 17:28:10 volumio volumio[997]: info: Discovery: Getting this device information
Feb 28 17:28:10 volumio volumio[997]: info: CoreCommandRouter::volumioGetState
Feb 28 17:28:10 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:28:10 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 28 17:28:11 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:11 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:12 volumio nmbd[1778]: [2025/02/28 17:28:12.420420, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database)
Feb 28 17:28:12 volumio nmbd[1778]: wins_write_database: Can't open /var/lib/samba/wins.dat.1778: Read-only file system
Feb 28 17:28:12 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_read_inode_bitmap:203: comm nmbd: Cannot read inode bitmap - block_group = 6720, inode_bitmap = 55050257
Feb 28 17:28:12 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_journal_check_start:83: comm nmbd: Detected aborted journal
Feb 28 17:28:12 volumio kernel: EXT4-fs (mmcblk0p3): Remounting filesystem read-only
Feb 28 17:28:13 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:13 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:14 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:14 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:16 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:16 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:18 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:18 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:20 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:20 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:21 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:21 volumio kernel: mmc0: error -110 whilst initialising SD card
Feb 28 17:28:23 volumio volumio[997]: verbose: New Socket.io Connection to 192.168.0.156 from 192.168.0.224 UA: Mozilla/5.0 (Linux; Android 10; YAL-L21 Build/HUAWEIYAL-L61; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.122 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::volumioGetVisibleSources
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::volumioGetState
Feb 28 17:28:23 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::volumioGetQueue
Feb 28 17:28:23 volumio volumio[997]: info: CoreStateMachine::getQueue
Feb 28 17:28:23 volumio volumio[997]: info: CorePlayQueue::getQueue
Feb 28 17:28:23 volumio volumio[997]: info: Listing playlists
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 28 17:28:23 volumio volumio[997]: info: Received Get System Info
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 28 17:28:23 volumio volumio[997]: info: Discovery: Getting this device information
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::volumioGetState
Feb 28 17:28:23 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 28 17:28:23 volumio volumio[997]: info: CoreCommandRouter::volumioGetState
Feb 28 17:28:23 volumio volumio[997]: info: CorePlayQueue::getTrack 0
Feb 28 17:28:23 volumio volumio[997]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 28 17:28:23 volumio kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #13386390: lblock 0: comm node: error -5 reading directory block
Feb 28 17:28:23 volumio kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #13386338: lblock 0: comm node: error -5 reading directory block
Feb 28 17:28:23 volumio volumio[997]: Error: EIO: i/o error, scandir '/data/plugins/'
Feb 28 17:28:23 volumio volumio[997]: at Object.readdirSync (fs.js:1021:3)
Feb 28 17:28:23 volumio volumio[997]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30)
Feb 28 17:28:23 volumio volumio[997]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1521:29)
Feb 28 17:28:23 volumio volumio[997]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1328:45)
Feb 28 17:28:23 volumio volumio[997]: at Socket.emit (events.js:315:20)
Feb 28 17:28:23 volumio volumio[997]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Feb 28 17:28:23 volumio volumio[997]: at processTicksAndRejections (internal/process/task_queues.js:75:11) {
Feb 28 17:28:23 volumio volumio[997]: errno: -5,
Feb 28 17:28:23 volumio volumio[997]: syscall: 'scandir',
Feb 28 17:28:23 volumio volumio[997]: code: 'EIO',
Feb 28 17:28:23 volumio volumio[997]: path: '/data/plugins/'
Feb 28 17:28:23 volumio volumio[997]: }
Feb 28 17:28:23 volumio volumio[997]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 28 17:28:23 volumio sudo[1788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-28 17:27
Feb 28 17:28:23 volumio sudo[1788]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 28 17:28:23 volumio kernel: mmc0: card never left busy state
Feb 28 17:28:23 volumio kernel: mmc0: error -110 whilst initialising SD card
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="5fd3886148e72a9cecd88a772d1f6079fb64d11a"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST"
VOLUMIO_VERSION="3.742"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"