-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Tue 2025-12-16 05:52:15 +07. --
Dec 16 05:51:02 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:06 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:10 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:14 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:18 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:22 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:26 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:30 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:34 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:38 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:41 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare
Dec 16 05:51:41 chot sudo[17344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/aaa
Dec 16 05:51:41 chot sudo[17344]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 16 05:51:41 chot sudo[17344]: pam_unix(sudo:session): session closed for user root
Dec 16 05:51:42 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Dec 16 05:51:42 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:46 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:47 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare
Dec 16 05:51:47 chot sudo[17361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/aaa
Dec 16 05:51:47 chot sudo[17361]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 16 05:51:47 chot sudo[17361]: pam_unix(sudo:session): session closed for user root
Dec 16 05:51:47 chot volumio[763]: error: Mount point '/mnt/NAS/aaa' cannot be removed. Error: exec error Error: Command failed: /usr/bin/sudo /bin/umount '/mnt/NAS/aaa'
Dec 16 05:51:47 chot volumio[763]: umount: /mnt/NAS/aaa: target is busy.
Dec 16 05:51:48 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:50 chot volumio[763]: info:
Dec 16 05:51:50 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:51:50 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:50 chot volumio[763]: info:
Dec 16 05:51:50 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:51:50 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:50 chot volumio[763]: info: sendMpdCommand status took 3 milliseconds
Dec 16 05:51:50 chot volumio[763]: info: sendMpdCommand status took 4 milliseconds
Dec 16 05:51:50 chot volumio[763]: info: sendMpdCommand status took 3 milliseconds
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:50 chot volumio[763]: info: Command Router : Notfying DB Updatetrue
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:50 chot volumio[763]: info: Command Router : Notfying DB Updatetrue
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:50 chot volumio[763]: info:
Dec 16 05:51:50 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:51:50 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:50 chot volumio[763]: info: sendMpdCommand playlistinfo took 24 milliseconds
Dec 16 05:51:50 chot volumio[763]: info: sendMpdCommand playlistinfo took 9 milliseconds
Dec 16 05:51:50 chot volumio[763]: info: sendMpdCommand status took 3 milliseconds
Dec 16 05:51:50 chot volumio[763]: info: sendMpdCommand status took 2 milliseconds
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::parseTrackInfo
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::parseTrackInfo
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:50 chot volumio[763]: info: Command Router : Notfying DB Updatetrue
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:51:50 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:51:50 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:50 chot volumio[763]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":378895,"duration":442,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"After The Dance [Long Version] (feat. El DeBarge) (2021 Remastered)","artist":"Fourplay, El Debarge","album":"Fourplay (30th Anniversary Edition)","uri":"NAS/aaa/Fourplay (30th Anniversary Edition) [DSD · 64] (1991-2021)/12 - After The Dance Long Version (feat El DeBarge) (2021 Remastered).dsf","trackType":"dsf"}
Dec 16 05:51:50 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::syncState stateService pause
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::syncState currentStatus pause
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:50 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::stPlaybackTimer
Dec 16 05:51:50 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:51:50 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:50 chot volumio[763]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":378895,"duration":442,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"After The Dance [Long Version] (feat. El DeBarge) (2021 Remastered)","artist":"Fourplay, El Debarge","album":"Fourplay (30th Anniversary Edition)","uri":"NAS/aaa/Fourplay (30th Anniversary Edition) [DSD · 64] (1991-2021)/12 - After The Dance Long Version (feat El DeBarge) (2021 Remastered).dsf","trackType":"dsf"}
Dec 16 05:51:50 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::syncState stateService pause
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::syncState currentStatus pause
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:50 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::stPlaybackTimer
Dec 16 05:51:50 chot volumio[763]: info: ------------------------------ 109ms
Dec 16 05:51:50 chot volumio[763]: info: ------------------------------ 109ms
Dec 16 05:51:50 chot volumio[763]: info: [1765839110267] Database update started
Dec 16 05:51:50 chot volumio[763]: info: sendMpdCommand playlistinfo took 16 milliseconds
Dec 16 05:51:50 chot volumio[763]: verbose: ControllerMpd::parseTrackInfo
Dec 16 05:51:50 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:51:50 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:50 chot volumio[763]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":378895,"duration":442,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"After The Dance [Long Version] (feat. El DeBarge) (2021 Remastered)","artist":"Fourplay, El Debarge","album":"Fourplay (30th Anniversary Edition)","uri":"NAS/aaa/Fourplay (30th Anniversary Edition) [DSD · 64] (1991-2021)/12 - After The Dance Long Version (feat El DeBarge) (2021 Remastered).dsf","trackType":"dsf"}
Dec 16 05:51:50 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::syncState stateService pause
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::syncState currentStatus pause
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:50 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:50 chot volumio[763]: info: CoreStateMachine::stPlaybackTimer
Dec 16 05:51:50 chot volumio[763]: info: ------------------------------ 41ms
Dec 16 05:51:50 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:52 chot volumio[763]: info:
Dec 16 05:51:52 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:51:52 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:52 chot volumio[763]: info:
Dec 16 05:51:52 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:51:52 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:52 chot volumio[763]: info: sendMpdCommand status took 3 milliseconds
Dec 16 05:51:52 chot volumio[763]: info: sendMpdCommand status took 3 milliseconds
Dec 16 05:51:52 chot volumio[763]: info: sendMpdCommand status took 2 milliseconds
Dec 16 05:51:52 chot volumio[763]: info: sendMpdCommand status took 1 milliseconds
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:52 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:52 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:52 chot volumio[763]: info:
Dec 16 05:51:52 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:51:52 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:52 chot volumio[763]: info: sendMpdCommand playlistinfo took 16 milliseconds
Dec 16 05:51:52 chot volumio[763]: info: sendMpdCommand playlistinfo took 3 milliseconds
Dec 16 05:51:52 chot volumio[763]: info: sendMpdCommand status took 1 milliseconds
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::parseTrackInfo
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:52 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:51:52 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:51:52 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:52 chot volumio[763]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":378895,"duration":442,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"After The Dance [Long Version] (feat. El DeBarge) (2021 Remastered)","artist":"Fourplay, El Debarge","album":"Fourplay (30th Anniversary Edition)","uri":"NAS/aaa/Fourplay (30th Anniversary Edition) [DSD · 64] (1991-2021)/12 - After The Dance Long Version (feat El DeBarge) (2021 Remastered).dsf","trackType":"dsf"}
Dec 16 05:51:52 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::syncState stateService pause
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::syncState currentStatus pause
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:52 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::stPlaybackTimer
Dec 16 05:51:52 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:51:52 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:52 chot volumio[763]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":378895,"duration":442,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"After The Dance [Long Version] (feat. El DeBarge) (2021 Remastered)","artist":"Fourplay, El Debarge","album":"Fourplay (30th Anniversary Edition)","uri":"NAS/aaa/Fourplay (30th Anniversary Edition) [DSD · 64] (1991-2021)/12 - After The Dance Long Version (feat El DeBarge) (2021 Remastered).dsf","trackType":"dsf"}
Dec 16 05:51:52 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::syncState stateService pause
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::syncState currentStatus pause
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:52 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::stPlaybackTimer
Dec 16 05:51:52 chot volumio[763]: info: ------------------------------ 64ms
Dec 16 05:51:52 chot volumio[763]: info: ------------------------------ 62ms
Dec 16 05:51:52 chot volumio[763]: info: sendMpdCommand playlistinfo took 11 milliseconds
Dec 16 05:51:52 chot volumio[763]: verbose: ControllerMpd::parseTrackInfo
Dec 16 05:51:52 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:51:52 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:52 chot volumio[763]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":378895,"duration":442,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"After The Dance [Long Version] (feat. El DeBarge) (2021 Remastered)","artist":"Fourplay, El Debarge","album":"Fourplay (30th Anniversary Edition)","uri":"NAS/aaa/Fourplay (30th Anniversary Edition) [DSD · 64] (1991-2021)/12 - After The Dance Long Version (feat El DeBarge) (2021 Remastered).dsf","trackType":"dsf"}
Dec 16 05:51:52 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::syncState stateService pause
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::syncState currentStatus pause
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:52 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:52 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:52 chot volumio[763]: info: CoreStateMachine::stPlaybackTimer
Dec 16 05:51:52 chot volumio[763]: info: ------------------------------ 34ms
Dec 16 05:51:55 chot volumio[763]: info: CoreCommandRouter::volumioGetState
Dec 16 05:51:55 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:55 chot volumio[763]: info: CoreCommandRouter::volumioGetQueue
Dec 16 05:51:55 chot volumio[763]: info: CoreStateMachine::getQueue
Dec 16 05:51:55 chot volumio[763]: info: CorePlayQueue::getQueue
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioClearQueue
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::ClearQueue
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::stop
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::updateTrackBlock
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrackBlock
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::stPlaybackTimer
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::serviceStop
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::serviceStop
Dec 16 05:51:56 chot volumio[763]: info: ControllerMpd::stop
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand stop
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::clearPlayQueue
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::saveQueue
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushQueue
Dec 16 05:51:56 chot volumio[763]: info:
Dec 16 05:51:56 chot volumio[763]: ---------------------------- MPD announces state update: player
Dec 16 05:51:56 chot volumio[763]: info: sendMpdCommand stop took 12 milliseconds
Dec 16 05:51:56 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:56 chot volumio[763]: info: sendMpdCommand status took 4 milliseconds
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:51:56 chot volumio[763]: info:
Dec 16 05:51:56 chot volumio[763]: ---------------------------- MPD announces state update: player
Dec 16 05:51:56 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:56 chot volumio[763]: info:
Dec 16 05:51:56 chot volumio[763]: ---------------------------- MPD announces state update: player
Dec 16 05:51:56 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:51:56 chot volumio[763]: info: sendMpdCommand playlistinfo took 9 milliseconds
Dec 16 05:51:56 chot volumio[763]: info: sendMpdCommand status took 8 milliseconds
Dec 16 05:51:56 chot volumio[763]: info: sendMpdCommand status took 8 milliseconds
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::parseTrackInfo
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:51:56 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"After The Dance [Long Version] (feat. El DeBarge) (2021 Remastered)","artist":"Fourplay, El Debarge","album":"Fourplay (30th Anniversary Edition)","uri":"NAS/aaa/Fourplay (30th Anniversary Edition) [DSD · 64] (1991-2021)/12 - After The Dance Long Version (feat El DeBarge) (2021 Remastered).dsf","trackType":"dsf"}
Dec 16 05:51:56 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: No code
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: ------------------------------ 33ms
Dec 16 05:51:56 chot volumio[763]: info: sendMpdCommand playlistinfo took 20 milliseconds
Dec 16 05:51:56 chot volumio[763]: info: sendMpdCommand playlistinfo took 19 milliseconds
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::parseTrackInfo
Dec 16 05:51:56 chot volumio[763]: verbose: ControllerMpd::parseTrackInfo
Dec 16 05:51:56 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"After The Dance [Long Version] (feat. El DeBarge) (2021 Remastered)","artist":"Fourplay, El Debarge","album":"Fourplay (30th Anniversary Edition)","uri":"NAS/aaa/Fourplay (30th Anniversary Edition) [DSD · 64] (1991-2021)/12 - After The Dance Long Version (feat El DeBarge) (2021 Remastered).dsf","trackType":"dsf"}
Dec 16 05:51:56 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: No code
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"After The Dance [Long Version] (feat. El DeBarge) (2021 Remastered)","artist":"Fourplay, El Debarge","album":"Fourplay (30th Anniversary Edition)","uri":"NAS/aaa/Fourplay (30th Anniversary Edition) [DSD · 64] (1991-2021)/12 - After The Dance Long Version (feat El DeBarge) (2021 Remastered).dsf","trackType":"dsf"}
Dec 16 05:51:56 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: No code
Dec 16 05:51:56 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:51:56 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:51:56 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:51:56 chot volumio[763]: info: ------------------------------ 50ms
Dec 16 05:51:56 chot volumio[763]: info: ------------------------------ 50ms
Dec 16 05:51:58 chot volumio[763]: info: CoreCommandRouter::volumioGetState
Dec 16 05:51:58 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 16 05:52:00 chot volumio[763]: info: Retrieving Cloud Streaming UI
Dec 16 05:52:00 chot volumio[763]: info: Getting Tidal Cloud Configuration
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 16 05:52:00 chot volumio[763]: info: Getting Qobuz Cloud Configuration
Dec 16 05:52:00 chot volumio[763]: info: Asking plugin for UI Config
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 16 05:52:00 chot volumio[763]: info: Getting Spotify Cloud Configuration
Dec 16 05:52:00 chot volumio[763]: info: Asking plugin for UI Config
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 16 05:52:00 chot volumio[763]: info: Saving Spotify Acccount
Dec 16 05:52:00 chot volumio[763]: info: Got Tidal Cloud Configuration
Dec 16 05:52:00 chot volumio[763]: info: Got it
Dec 16 05:52:00 chot volumio[763]: info: Got it
Dec 16 05:52:00 chot volumio[763]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 16 05:52:00 chot volumio[763]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 16 05:52:00 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:52:01 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Dec 16 05:52:04 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:52:06 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare
Dec 16 05:52:06 chot sudo[17462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/aaa
Dec 16 05:52:06 chot sudo[17462]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 16 05:52:06 chot systemd[1]: mnt-NAS-aaa.mount: Succeeded.
Dec 16 05:52:06 chot sudo[17462]: pam_unix(sudo:session): session closed for user root
Dec 16 05:52:06 chot volumio[763]: info: Share aaa successfully unmounted
Dec 16 05:52:07 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 3 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 2 milliseconds
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: info: Command Router : Notfying DB Updatetrue
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info: MPD Database updated - AlbumList cache refreshed
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces state update: database
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces system playlist update
Dec 16 05:52:09 chot volumio[763]: info: Ignoring MPD Status Update
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 76 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 76 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand playlistinfo took 5 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 4 milliseconds
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseTrackInfo
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:09 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: No code
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:09 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: No code
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 127ms
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 130ms
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 57ms
Dec 16 05:52:09 chot volumio[763]: info: MPD Database updated - AlbumList cache refreshed
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces state update: database
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces system playlist update
Dec 16 05:52:09 chot volumio[763]: info: Ignoring MPD Status Update
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info: MPD Database updated - AlbumList cache refreshed
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces state update: database
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces system playlist update
Dec 16 05:52:09 chot volumio[763]: info: Ignoring MPD Status Update
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info: [1765839129436] Database update started
Dec 16 05:52:09 chot volumio[763]: error: updateQueue error: null
Dec 16 05:52:09 chot volumio[763]: error: updateQueue error: null
Dec 16 05:52:09 chot volumio[763]: error: updateQueue error: null
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 99 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 99 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 100ms
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 99 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 44 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 43 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 46ms
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 43 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 44 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 45ms
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:09 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: No code
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:09 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: No code
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:09 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: No code
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:09 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: No code
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:09 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: No code
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 184ms
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 184ms
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 130ms
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 129ms
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 127ms
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info:
Dec 16 05:52:09 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 6 milliseconds
Dec 16 05:52:09 chot volumio[763]: info: sendMpdCommand status took 6 milliseconds
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:09 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:09 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:09 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: No code
Dec 16 05:52:09 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:09 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:09 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:09 chot volumio[763]: info: ------------------------------ 26ms
Dec 16 05:52:12 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand rescan
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: info:
Dec 16 05:52:13 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand rescan took 2 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: info:
Dec 16 05:52:13 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 3 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 2 milliseconds
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:13 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:13 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: No code
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: ------------------------------ 23ms
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: info:
Dec 16 05:52:13 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: info:
Dec 16 05:52:13 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 28 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 27 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 4 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 4 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 3 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 2 milliseconds
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:13 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:13 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:13 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:13 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: No code
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:13 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: No code
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:13 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: No code
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: ------------------------------ 95ms
Dec 16 05:52:13 chot volumio[763]: info: ------------------------------ 72ms
Dec 16 05:52:13 chot volumio[763]: info: ------------------------------ 71ms
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: info:
Dec 16 05:52:13 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: info:
Dec 16 05:52:13 chot volumio[763]: ---------------------------- MPD announces state update: update
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::getState
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::sendMpdCommand status
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 7 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 7 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 7 milliseconds
Dec 16 05:52:13 chot volumio[763]: info: sendMpdCommand status took 6 milliseconds
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:13 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:13 chot volumio[763]: info: Command Router : Notfying DB Updatefalse
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::Close All Modals sent
Dec 16 05:52:13 chot volumio[763]: verbose: ControllerMpd::parseState
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Dec 16 05:52:13 chot volumio[763]: verbose: CURRENT POSITION 61
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState stateService stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::syncState currentStatus stop
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: No code
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: ControllerMpd::pushState
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::servicePushState
Dec 16 05:52:13 chot volumio[763]: info: CoreStateMachine::pushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: CorePlayQueue::getTrack 61
Dec 16 05:52:13 chot volumio[763]: info: CoreCommandRouter::volumioPushState
Dec 16 05:52:13 chot volumio[763]: info: ------------------------------ 58ms
Dec 16 05:52:13 chot volumio[763]: info: ------------------------------ 58ms
Dec 16 05:52:14 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Dec 16 05:52:14 chot volumio[763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Dec 16 05:52:15 chot volumio[763]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 16 05:52:15 chot volumio[763]: Error: getaddrinfo -3007
Dec 16 05:52:15 chot volumio[763]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11)
Dec 16 05:52:15 chot volumio[763]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10)
Dec 16 05:52:15 chot volumio[763]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) {
Dec 16 05:52:15 chot volumio[763]: code: -3007,
Dec 16 05:52:15 chot volumio[763]: errno: -3007,
Dec 16 05:52:15 chot volumio[763]: syscall: 'getaddrinfo'
Dec 16 05:52:15 chot volumio[763]: }
Dec 16 05:52:15 chot volumio[763]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 16 05:52:15 chot sudo[17526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 05:51
Dec 16 05:52:15 chot sudo[17526]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:17:52 PM CET"
VOLUMIO_VERSION="3.874"
VOLUMIO_HARDWARE="tinkerboard"
VOLUMIO_DEVICENAME="Asus Tinkerboard"
VOLUMIO_HASH="3b20d491450ccf59e0e519a97572dc60"