-- Logs begin at Fri 2025-03-07 22:37:07 +07, end at Fri 2025-03-07 22:50:44 +07. -- Mar 07 22:49:01 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:49:03 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 22:49:03 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 22:49:03 volumio volumio[832]: info: Discovery: Getting this device information Mar 07 22:49:03 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Mar 07 22:49:03 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 22:49:05 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 07 22:49:05 volumio volumio[832]: info: Share Linksys00673 successfully unmounted Mar 07 22:49:05 volumio sudo[4377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.2:/Media /mnt/NAS/Linksys00673 Mar 07 22:49:05 volumio sudo[4377]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:49:05 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:49:09 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:49:13 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand rescan Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:16 volumio volumio[832]: info: Mar 07 22:49:16 volumio volumio[832]: ---------------------------- MPD announces state update: update Mar 07 22:49:16 volumio volumio[832]: info: sendMpdCommand rescan took 5 milliseconds Mar 07 22:49:16 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:16 volumio volumio[832]: info: sendMpdCommand status took 5 milliseconds Mar 07 22:49:16 volumio volumio[832]: info: sendMpdCommand status took 2 milliseconds Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:16 volumio volumio[832]: info: Command Router : Notfying DB Updatetrue Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:16 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getTrack 3 Mar 07 22:49:16 volumio volumio[832]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 07 22:49:16 volumio volumio[832]: verbose: CURRENT POSITION 3 Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus play Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::play index undefined Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::startPlaybackTimer Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:16 volumio volumio[832]: info: [1741362556892] ControllerUPNPBrowser::clearAddPlayTrack Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Mar 07 22:49:16 volumio volumio[832]: info: ------------------------------ 58ms Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:16 volumio volumio[832]: info: Mar 07 22:49:16 volumio volumio[832]: ---------------------------- MPD announces state update: update Mar 07 22:49:16 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:16 volumio volumio[832]: info: sendMpdCommand stop took 15 milliseconds Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand clear Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:16 volumio volumio[832]: info: Mar 07 22:49:16 volumio volumio[832]: ---------------------------- MPD announces system playlist update Mar 07 22:49:16 volumio volumio[832]: info: Ignoring MPD Status Update Mar 07 22:49:16 volumio volumio[832]: info: sendMpdCommand status took 16 milliseconds Mar 07 22:49:16 volumio volumio[832]: info: sendMpdCommand status took 15 milliseconds Mar 07 22:49:16 volumio volumio[832]: info: sendMpdCommand clear took 15 milliseconds Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:16 volumio volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:16 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.2:9999/MediaItems/1036.flac" Mar 07 22:49:16 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:16 volumio volumio[832]: 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} Mar 07 22:49:16 volumio volumio[832]: verbose: CURRENT POSITION 4 Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:16 volumio volumio[832]: info: No code Mar 07 22:49:16 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:16 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:16 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:17 volumio volumio[832]: info: ------------------------------ 116ms Mar 07 22:49:17 volumio volumio[832]: error: updateQueue error: null Mar 07 22:49:17 volumio volumio[832]: info: ------------------------------ 103ms Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.2:9999/MediaItems/1036.flac" Mar 07 22:49:17 volumio volumio[832]: info: Mar 07 22:49:17 volumio volumio[832]: ---------------------------- MPD announces system playlist update Mar 07 22:49:17 volumio volumio[832]: info: Ignoring MPD Status Update Mar 07 22:49:17 volumio volumio[832]: info: sendMpdCommand add "http://192.168.1.2:9999/MediaItems/1036.flac" took 1 milliseconds Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand play Mar 07 22:49:17 volumio volumio[832]: info: ------------------------------ 2ms Mar 07 22:49:17 volumio volumio[832]: info: sendMpdCommand play took 8 milliseconds Mar 07 22:49:17 volumio volumio[832]: info: Mar 07 22:49:17 volumio volumio[832]: ---------------------------- MPD announces state update: player Mar 07 22:49:17 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:17 volumio volumio[832]: info: Mar 07 22:49:17 volumio volumio[832]: ---------------------------- MPD announces state update: player Mar 07 22:49:17 volumio volumio[832]: info: sendMpdCommand status took 6 milliseconds Mar 07 22:49:17 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:49:17 volumio volumio[832]: info: sendMpdCommand status took 3 milliseconds Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:49:17 volumio volumio[832]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:49:17 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:49:17 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:17 volumio volumio[832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1036.flac","artist":null,"album":null,"uri":"http://192.168.1.2:9999/MediaItems/1036.flac","trackType":"flac"} Mar 07 22:49:17 volumio volumio[832]: verbose: CURRENT POSITION 4 Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::syncState stateService play Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 07 22:49:17 volumio volumio[832]: info: ------------------------------ 13ms Mar 07 22:49:17 volumio volumio[832]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:49:17 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:49:17 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:17 volumio volumio[832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1036.flac","artist":null,"album":null,"uri":"http://192.168.1.2:9999/MediaItems/1036.flac","trackType":"flac"} Mar 07 22:49:17 volumio volumio[832]: verbose: CURRENT POSITION 4 Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::syncState stateService play Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus play Mar 07 22:49:17 volumio volumio[832]: info: Received an update from plugin. extracting info from payload Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:17 volumio volumio[832]: info: ------------------------------ 62ms Mar 07 22:49:17 volumio volumio[832]: info: Mar 07 22:49:17 volumio volumio[832]: ---------------------------- MPD announces system playlist update Mar 07 22:49:17 volumio volumio[832]: info: Ignoring MPD Status Update Mar 07 22:49:17 volumio volumio[832]: info: Mar 07 22:49:17 volumio volumio[832]: ---------------------------- MPD announces state update: player Mar 07 22:49:17 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:17 volumio volumio[832]: info: ------------------------------ 27ms Mar 07 22:49:17 volumio volumio[832]: info: sendMpdCommand status took 8 milliseconds Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:49:17 volumio volumio[832]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 07 22:49:17 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:49:17 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:49:17 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:17 volumio volumio[832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":292,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2358 Kbps","isStreaming":false,"title":"Misty","artist":"Bob James Trio ","album":"Feel Like Making Live!","uri":"http://192.168.1.2:9999/MediaItems/1036.flac","trackType":"flac"} Mar 07 22:49:17 volumio volumio[832]: verbose: CURRENT POSITION 4 Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::syncState stateService play Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus play Mar 07 22:49:17 volumio volumio[832]: info: Received an update from plugin. extracting info from payload Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:17 volumio volumio[832]: info: ------------------------------ 88ms Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:17 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:17 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:17 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:17 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:49:21 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:49:25 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:49:27 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Mar 07 22:49:27 volumio ntpd[1634]: Soliciting pool server 203.131.222.11 Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioNext Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::next Mar 07 22:49:30 volumio volumio[832]: info: ControllerMpd::next Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand next Mar 07 22:49:30 volumio volumio[832]: info: Mar 07 22:49:30 volumio volumio[832]: ---------------------------- MPD announces state update: player Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand next took 12 milliseconds Mar 07 22:49:30 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand status took 0 milliseconds Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:30 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getTrack 4 Mar 07 22:49:30 volumio volumio[832]: 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} Mar 07 22:49:30 volumio volumio[832]: verbose: CURRENT POSITION 4 Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus play Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::play index undefined Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::startPlaybackTimer Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:49:30 volumio volumio[832]: info: [1741362570527] ControllerUPNPBrowser::clearAddPlayTrack Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand stop Mar 07 22:49:30 volumio volumio[832]: info: ------------------------------ 23ms Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand stop took 20 milliseconds Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand clear Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:30 volumio volumio[832]: info: Mar 07 22:49:30 volumio volumio[832]: ---------------------------- MPD announces system playlist update Mar 07 22:49:30 volumio volumio[832]: info: Ignoring MPD Status Update Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand clear took 11 milliseconds Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.2:9999/MediaItems/1037.flac" Mar 07 22:49:30 volumio volumio[832]: error: updateQueue error: null Mar 07 22:49:30 volumio volumio[832]: info: ------------------------------ 45ms Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.2:9999/MediaItems/1037.flac" Mar 07 22:49:30 volumio volumio[832]: info: Mar 07 22:49:30 volumio volumio[832]: ---------------------------- MPD announces system playlist update Mar 07 22:49:30 volumio volumio[832]: info: Ignoring MPD Status Update Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand add "http://192.168.1.2:9999/MediaItems/1037.flac" took 1 milliseconds Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand play Mar 07 22:49:30 volumio volumio[832]: info: ------------------------------ 3ms Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand play took 2 milliseconds Mar 07 22:49:30 volumio volumio[832]: info: Mar 07 22:49:30 volumio volumio[832]: ---------------------------- MPD announces state update: player Mar 07 22:49:30 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:30 volumio volumio[832]: info: Mar 07 22:49:30 volumio volumio[832]: ---------------------------- MPD announces state update: player Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand status took 4 milliseconds Mar 07 22:49:30 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand status took 18 milliseconds Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:49:30 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:49:30 volumio volumio[832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1037.flac","artist":null,"album":null,"uri":"http://192.168.1.2:9999/MediaItems/1037.flac","trackType":"flac"} Mar 07 22:49:30 volumio volumio[832]: verbose: CURRENT POSITION 5 Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::syncState stateService play Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 07 22:49:30 volumio volumio[832]: info: ------------------------------ 57ms Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:49:30 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:49:30 volumio volumio[832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1037.flac","artist":null,"album":null,"uri":"http://192.168.1.2:9999/MediaItems/1037.flac","trackType":"flac"} Mar 07 22:49:30 volumio volumio[832]: verbose: CURRENT POSITION 5 Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::syncState stateService play Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus play Mar 07 22:49:30 volumio volumio[832]: info: Received an update from plugin. extracting info from payload Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:30 volumio volumio[832]: info: ------------------------------ 145ms Mar 07 22:49:30 volumio volumio[832]: info: Mar 07 22:49:30 volumio volumio[832]: ---------------------------- MPD announces system playlist update Mar 07 22:49:30 volumio volumio[832]: info: Ignoring MPD Status Update Mar 07 22:49:30 volumio volumio[832]: info: Mar 07 22:49:30 volumio volumio[832]: ---------------------------- MPD announces state update: player Mar 07 22:49:30 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:49:30 volumio volumio[832]: info: ------------------------------ 5ms Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand status took 4 milliseconds Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:49:30 volumio volumio[832]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 07 22:49:30 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:49:30 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:49:30 volumio volumio[832]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2367 Kbps","isStreaming":false,"title":"Avalabop","artist":"Bob James Trio ","album":"Feel Like Making Live!","uri":"http://192.168.1.2:9999/MediaItems/1037.flac","trackType":"flac"} Mar 07 22:49:30 volumio volumio[832]: verbose: CURRENT POSITION 5 Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::syncState stateService play Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus play Mar 07 22:49:30 volumio volumio[832]: info: Received an update from plugin. extracting info from payload Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:30 volumio volumio[832]: info: ------------------------------ 65ms Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:30 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:30 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:30 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:33 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 22:49:33 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 22:49:33 volumio volumio[832]: info: Discovery: Getting this device information Mar 07 22:49:33 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Mar 07 22:49:33 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 22:49:35 volumio volumio[832]: info: VolumeController::SetAlsaVolume- Mar 07 22:49:35 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:35 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:35 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:35 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:35 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:35 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:36 volumio volumio[832]: info: VolumeController::SetAlsaVolume- Mar 07 22:49:36 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:36 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:36 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:36 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:36 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:36 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:36 volumio volumio[832]: info: VolumeController::SetAlsaVolume- Mar 07 22:49:36 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:36 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:36 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:36 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:36 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:36 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:36 volumio volumio[832]: info: VolumeController::SetAlsaVolume- Mar 07 22:49:36 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:49:36 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:49:36 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:49:36 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:49:36 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:49:36 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:49:36 volumio ntpd[1634]: Soliciting pool server 122.155.180.231 Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 07 22:49:40 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 07 22:49:41 volumio ntpd[1634]: Soliciting pool server 2406:3100:1010:c03::149 Mar 07 22:49:50 volumio sudo[4377]: pam_unix(sudo:session): session closed for user root Mar 07 22:49:50 volumio volumio[832]: info: An error occurred mounting the new share. Rolling back configuration Mar 07 22:49:51 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 07 22:49:57 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Mar 07 22:50:02 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 07 22:50:02 volumio volumio[832]: info: Preload queue cleared Mar 07 22:50:03 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 22:50:03 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 22:50:03 volumio volumio[832]: info: Discovery: Getting this device information Mar 07 22:50:03 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Mar 07 22:50:03 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 22:50:05 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Mar 07 22:50:06 volumio volumio[832]: info: CoreCommandRouter::volumioPause Mar 07 22:50:06 volumio volumio[832]: info: CoreStateMachine::pause Mar 07 22:50:06 volumio volumio[832]: info: CoreStateMachine::stPlaybackTimer Mar 07 22:50:06 volumio volumio[832]: info: CoreStateMachine::servicePause Mar 07 22:50:06 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:50:06 volumio volumio[832]: info: CoreCommandRouter::servicePause Mar 07 22:50:06 volumio volumio[832]: info: [1741362606864] ControllerUPNPBrowser::pause Mar 07 22:50:06 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand pause Mar 07 22:50:06 volumio volumio[832]: info: Mar 07 22:50:06 volumio volumio[832]: ---------------------------- MPD announces state update: player Mar 07 22:50:06 volumio volumio[832]: info: sendMpdCommand pause took 2 milliseconds Mar 07 22:50:06 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:50:06 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:50:06 volumio volumio[832]: info: sendMpdCommand status took 0 milliseconds Mar 07 22:50:06 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:50:06 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:50:06 volumio volumio[832]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 07 22:50:06 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:50:06 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:50:06 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:50:06 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:50:06 volumio volumio[832]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37117,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2726 Kbps","isStreaming":false,"title":"Avalabop","artist":"Bob James Trio ","album":"Feel Like Making Live!","uri":"http://192.168.1.2:9999/MediaItems/1037.flac","trackType":"flac"} Mar 07 22:50:06 volumio volumio[832]: verbose: CURRENT POSITION 5 Mar 07 22:50:06 volumio volumio[832]: info: CoreStateMachine::syncState stateService pause Mar 07 22:50:06 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus pause Mar 07 22:50:06 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:50:06 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:50:06 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:50:06 volumio volumio[832]: info: CoreStateMachine::stPlaybackTimer Mar 07 22:50:06 volumio volumio[832]: info: ------------------------------ 14ms Mar 07 22:50:06 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:50:06 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:50:06 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:50:10 volumio volumio[832]: info: Retrieving Cloud Streaming UI Mar 07 22:50:10 volumio volumio[832]: info: Getting Tidal Cloud Configuration Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:50:10 volumio volumio[832]: info: Getting Qobuz Cloud Configuration Mar 07 22:50:10 volumio volumio[832]: info: Asking plugin for UI Config Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:50:10 volumio volumio[832]: info: Getting Spotify Cloud Configuration Mar 07 22:50:10 volumio volumio[832]: info: Asking plugin for UI Config Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:50:10 volumio volumio[832]: info: Saving Spotify Acccount Mar 07 22:50:10 volumio volumio[832]: info: Got Tidal Cloud Configuration Mar 07 22:50:10 volumio volumio[832]: info: Got it Mar 07 22:50:10 volumio volumio[832]: info: Got it Mar 07 22:50:10 volumio volumio[832]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:50:10 volumio volumio[832]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:50:10 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 07 22:50:14 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand update Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:50:15 volumio volumio[832]: info: Mar 07 22:50:15 volumio volumio[832]: ---------------------------- MPD announces state update: update Mar 07 22:50:15 volumio volumio[832]: info: sendMpdCommand update took 3 milliseconds Mar 07 22:50:15 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:50:15 volumio volumio[832]: info: Mar 07 22:50:15 volumio volumio[832]: ---------------------------- MPD announces state update: update Mar 07 22:50:15 volumio volumio[832]: info: sendMpdCommand status took 7 milliseconds Mar 07 22:50:15 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:50:15 volumio volumio[832]: info: sendMpdCommand status took 6 milliseconds Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:50:15 volumio volumio[832]: info: Command Router : Notfying DB Updatetrue Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:50:15 volumio volumio[832]: info: sendMpdCommand status took 51 milliseconds Mar 07 22:50:15 volumio volumio[832]: info: sendMpdCommand status took 46 milliseconds Mar 07 22:50:15 volumio volumio[832]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:50:15 volumio volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:50:15 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:50:15 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:50:15 volumio volumio[832]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37117,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2726 Kbps","isStreaming":false,"title":"Avalabop","artist":"Bob James Trio ","album":"Feel Like Making Live!","uri":"http://192.168.1.2:9999/MediaItems/1037.flac","trackType":"flac"} Mar 07 22:50:15 volumio volumio[832]: verbose: CURRENT POSITION 5 Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::syncState stateService pause Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus pause Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::stPlaybackTimer Mar 07 22:50:15 volumio volumio[832]: info: ------------------------------ 84ms Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:50:15 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:50:15 volumio volumio[832]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 07 22:50:15 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:50:15 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:50:15 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:50:15 volumio volumio[832]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37117,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2726 Kbps","isStreaming":false,"title":"Avalabop","artist":"Bob James Trio ","album":"Feel Like Making Live!","uri":"http://192.168.1.2:9999/MediaItems/1037.flac","trackType":"flac"} Mar 07 22:50:15 volumio volumio[832]: verbose: CURRENT POSITION 5 Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::syncState stateService pause Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus pause Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::stPlaybackTimer Mar 07 22:50:15 volumio volumio[832]: info: ------------------------------ 98ms Mar 07 22:50:15 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:50:15 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:50:15 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:50:18 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:50:21 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand rescan Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:50:21 volumio volumio[832]: info: Mar 07 22:50:21 volumio volumio[832]: ---------------------------- MPD announces state update: update Mar 07 22:50:21 volumio volumio[832]: info: sendMpdCommand rescan took 3 milliseconds Mar 07 22:50:21 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:50:21 volumio volumio[832]: info: Mar 07 22:50:21 volumio volumio[832]: ---------------------------- MPD announces state update: update Mar 07 22:50:21 volumio volumio[832]: info: ControllerMpd::getState Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 07 22:50:21 volumio volumio[832]: info: sendMpdCommand status took 4 milliseconds Mar 07 22:50:21 volumio volumio[832]: info: sendMpdCommand status took 2 milliseconds Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:50:21 volumio volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 07 22:50:21 volumio volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:50:21 volumio volumio[832]: info: sendMpdCommand status took 26 milliseconds Mar 07 22:50:21 volumio volumio[832]: info: sendMpdCommand status took 26 milliseconds Mar 07 22:50:21 volumio volumio[832]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:50:21 volumio volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 07 22:50:21 volumio volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::parseState Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:50:21 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:50:21 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:50:21 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:50:21 volumio volumio[832]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37117,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2726 Kbps","isStreaming":false,"title":"Avalabop","artist":"Bob James Trio ","album":"Feel Like Making Live!","uri":"http://192.168.1.2:9999/MediaItems/1037.flac","trackType":"flac"} Mar 07 22:50:21 volumio volumio[832]: verbose: CURRENT POSITION 5 Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::syncState stateService pause Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus pause Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:50:21 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::stPlaybackTimer Mar 07 22:50:21 volumio volumio[832]: info: ------------------------------ 68ms Mar 07 22:50:21 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:50:21 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:50:21 volumio volumio[832]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 07 22:50:21 volumio volumio[832]: verbose: ControllerMpd::parseTrackInfo Mar 07 22:50:21 volumio volumio[832]: info: ControllerMpd::pushState Mar 07 22:50:21 volumio volumio[832]: info: CoreCommandRouter::servicePushState Mar 07 22:50:21 volumio volumio[832]: info: CorePlayQueue::getTrack 5 Mar 07 22:50:21 volumio volumio[832]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37117,"duration":363,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2726 Kbps","isStreaming":false,"title":"Avalabop","artist":"Bob James Trio ","album":"Feel Like Making Live!","uri":"http://192.168.1.2:9999/MediaItems/1037.flac","trackType":"flac"} Mar 07 22:50:21 volumio volumio[832]: verbose: CURRENT POSITION 5 Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::syncState stateService pause Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::syncState currentStatus pause Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::pushState Mar 07 22:50:21 volumio volumio[832]: info: CoreCommandRouter::volumioPushState Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::stPlaybackTimer Mar 07 22:50:21 volumio volumio[832]: info: ------------------------------ 87ms Mar 07 22:50:21 volumio volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 07 22:50:21 volumio volumio[832]: info: CoreStateMachine::getQueue Mar 07 22:50:21 volumio volumio[832]: info: CorePlayQueue::getQueue Mar 07 22:50:22 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:50:26 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:50:30 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:50:33 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 22:50:33 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 22:50:33 volumio volumio[832]: info: Discovery: Getting this device information Mar 07 22:50:33 volumio volumio[832]: info: CoreCommandRouter::volumioGetState Mar 07 22:50:33 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 22:50:34 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:50:38 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:50:39 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Mar 07 22:50:39 volumio volumio[832]: info: Share Linksys00673 successfully unmounted Mar 07 22:50:39 volumio sudo[4861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.2:/Media /mnt/NAS/Linksys00673 Mar 07 22:50:39 volumio sudo[4861]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:50:41 volumio ntpd[1634]: Soliciting pool server 122.155.180.231 Mar 07 22:50:42 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 07 22:50:43 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 07 22:50:43 volumio volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 07 22:50:43 volumio volumio[832]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 22:50:43 volumio volumio[832]: Error: getaddrinfo -3007 Mar 07 22:50:43 volumio volumio[832]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Mar 07 22:50:43 volumio volumio[832]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Mar 07 22:50:43 volumio volumio[832]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Mar 07 22:50:43 volumio volumio[832]: code: -3007, Mar 07 22:50:43 volumio volumio[832]: errno: -3007, Mar 07 22:50:43 volumio volumio[832]: syscall: 'getaddrinfo' Mar 07 22:50:43 volumio volumio[832]: } Mar 07 22:50:43 volumio volumio[832]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 22:50:44 volumio sudo[4893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-07 22:49 Mar 07 22:50:44 volumio sudo[4893]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:02:39 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="e62831d2665580a7904f9309b43b2eea"