Feb 10 23:37:00 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:00 volumio mpd[6843]: exception: No database Feb 10 23:37:04 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:04 volumio mpd[6843]: exception: No database Feb 10 23:37:04 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:04 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:04 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:04 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:04 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:08 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:08 volumio mpd[6843]: exception: No database Feb 10 23:37:09 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:09 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:09 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:09 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:09 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:11 volumio volumio[6670]: info: Clearing queue after UPNP request Feb 10 23:37:11 volumio volumio[6670]: info: CoreStateMachine::ClearQueue Feb 10 23:37:11 volumio volumio[6670]: info: CoreStateMachine::stop Feb 10 23:37:11 volumio volumio[6670]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 23:37:11 volumio volumio[6670]: info: CorePlayQueue::clearPlayQueue Feb 10 23:37:11 volumio volumio[6670]: info: CorePlayQueue::saveQueue Feb 10 23:37:11 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:11 volumio volumio[6670]: info: CoreCommandRouter::volumioPushQueue Feb 10 23:37:12 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:12 volumio mpd[6843]: exception: No database Feb 10 23:37:13 volumio volumio[6670]: info: Starting UPNP Playback Feb 10 23:37:13 volumio volumio[6670]: info: Preparing playback through UPNP Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::volumioGetState Feb 10 23:37:13 volumio volumio[6670]: info: CorePlayQueue::getTrack 0 Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 6ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 4ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 4ms Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 3ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 4ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 3ms Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 4ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 2ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 2ms Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 4ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 3ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 2ms Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 4ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 2ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 2ms Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 4ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 3ms Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 2ms Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces state update: player Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::getState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand status Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces state update: player Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::getState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand status Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces state update: player Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::getState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand status Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces state update: player Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand status took 11 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::getState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand status Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces state update: player Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::getState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand status Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces state update: player Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::getState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand status Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces state update: player Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand status took 17 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand status took 16 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::getState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand status Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand status took 10 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand status took 8 milliseconds Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseTrackInfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::pushState Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::servicePushState Feb 10 23:37:13 volumio volumio[6670]: verbose: In UPNP mode Feb 10 23:37:13 volumio volumio[6670]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"White Flag","artist":"Dido","album":"Life for Rent","uri":"http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac","trackType":"flac"} Feb 10 23:37:13 volumio volumio[6670]: verbose: CURRENT POSITION 0 Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::syncState stateService play Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::syncState currentStatus stop Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 43ms Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces state update: player Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::getState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand status Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces system playlist update Feb 10 23:37:13 volumio volumio[6670]: info: Ignoring MPD Status Update Feb 10 23:37:13 volumio volumio[6670]: info: Feb 10 23:37:13 volumio volumio[6670]: ---------------------------- MPD announces state update: player Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::getState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand status Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand status took 32 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 32ms Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand status took 29 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand playlistinfo took 26 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 10 23:37:13 volumio volumio[6670]: info: ------------------------------ 12ms Feb 10 23:37:13 volumio volumio[6670]: info: sendMpdCommand status took 14 milliseconds Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseTrackInfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseTrackInfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseTrackInfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseTrackInfo Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::parseState Feb 10 23:37:13 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::pushState Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::servicePushState Feb 10 23:37:13 volumio volumio[6670]: verbose: In UPNP mode Feb 10 23:37:13 volumio volumio[6670]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"White Flag","artist":"Dido","album":"Life for Rent","uri":"http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac","trackType":"flac"} Feb 10 23:37:13 volumio volumio[6670]: verbose: CURRENT POSITION 0 Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::syncState stateService play Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::syncState currentStatus play Feb 10 23:37:13 volumio volumio[6670]: info: Received an update from plugin. extracting info from payload Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:13 volumio volumio[6670]: info: ControllerMpd::pushState Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::servicePushState Feb 10 23:37:13 volumio volumio[6670]: verbose: In UPNP mode Feb 10 23:37:13 volumio volumio[6670]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"White Flag","artist":"Dido","album":"Life for Rent","uri":"http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac","trackType":"flac"} Feb 10 23:37:13 volumio volumio[6670]: verbose: CURRENT POSITION 0 Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::syncState stateService play Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::syncState currentStatus play Feb 10 23:37:13 volumio volumio[6670]: info: Received an update from plugin. extracting info from payload Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:13 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:13 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: ControllerMpd::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::servicePushState Feb 10 23:37:14 volumio volumio[6670]: verbose: In UPNP mode Feb 10 23:37:14 volumio volumio[6670]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"153 Kbps","isStreaming":false,"title":"White Flag","artist":"Dido","album":"Life for Rent","uri":"http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac","trackType":"flac"} Feb 10 23:37:14 volumio volumio[6670]: verbose: CURRENT POSITION 0 Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState stateService play Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState currentStatus play Feb 10 23:37:14 volumio volumio[6670]: info: Received an update from plugin. extracting info from payload Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: ControllerMpd::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::servicePushState Feb 10 23:37:14 volumio volumio[6670]: verbose: In UPNP mode Feb 10 23:37:14 volumio volumio[6670]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"153 Kbps","isStreaming":false,"title":"White Flag","artist":"Dido","album":"Life for Rent","uri":"http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac","trackType":"flac"} Feb 10 23:37:14 volumio volumio[6670]: verbose: CURRENT POSITION 0 Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState stateService play Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState currentStatus play Feb 10 23:37:14 volumio volumio[6670]: info: Received an update from plugin. extracting info from payload Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: ------------------------------ 170ms Feb 10 23:37:14 volumio volumio[6670]: info: ------------------------------ 169ms Feb 10 23:37:14 volumio volumio[6670]: info: ------------------------------ 165ms Feb 10 23:37:14 volumio volumio[6670]: info: ------------------------------ 159ms Feb 10 23:37:14 volumio volumio[6670]: info: ------------------------------ 131ms Feb 10 23:37:14 volumio volumio[6670]: info: sendMpdCommand status took 131 milliseconds Feb 10 23:37:14 volumio volumio[6670]: info: sendMpdCommand playlistinfo took 118 milliseconds Feb 10 23:37:14 volumio volumio[6670]: info: sendMpdCommand playlistinfo took 118 milliseconds Feb 10 23:37:14 volumio volumio[6670]: info: sendMpdCommand playlistinfo took 117 milliseconds Feb 10 23:37:14 volumio volumio[6670]: verbose: ControllerMpd::parseState Feb 10 23:37:14 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 23:37:14 volumio volumio[6670]: verbose: ControllerMpd::parseTrackInfo Feb 10 23:37:14 volumio volumio[6670]: verbose: ControllerMpd::parseTrackInfo Feb 10 23:37:14 volumio volumio[6670]: verbose: ControllerMpd::parseTrackInfo Feb 10 23:37:14 volumio volumio[6670]: info: ControllerMpd::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::servicePushState Feb 10 23:37:14 volumio volumio[6670]: verbose: In UPNP mode Feb 10 23:37:14 volumio volumio[6670]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"153 Kbps","isStreaming":false,"title":"White Flag","artist":"Dido","album":"Life for Rent","uri":"http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac","trackType":"flac"} Feb 10 23:37:14 volumio volumio[6670]: verbose: CURRENT POSITION 0 Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState stateService play Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState currentStatus play Feb 10 23:37:14 volumio volumio[6670]: info: Received an update from plugin. extracting info from payload Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: ControllerMpd::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::servicePushState Feb 10 23:37:14 volumio volumio[6670]: verbose: In UPNP mode Feb 10 23:37:14 volumio volumio[6670]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"640 Kbps","isStreaming":false,"title":"White Flag","artist":"Dido","album":"Life for Rent","uri":"http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac","trackType":"flac"} Feb 10 23:37:14 volumio volumio[6670]: verbose: CURRENT POSITION 0 Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState stateService play Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState currentStatus play Feb 10 23:37:14 volumio volumio[6670]: info: Received an update from plugin. extracting info from payload Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: ControllerMpd::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::servicePushState Feb 10 23:37:14 volumio volumio[6670]: verbose: In UPNP mode Feb 10 23:37:14 volumio volumio[6670]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"640 Kbps","isStreaming":false,"title":"White Flag","artist":"Dido","album":"Life for Rent","uri":"http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac","trackType":"flac"} Feb 10 23:37:14 volumio volumio[6670]: verbose: CURRENT POSITION 0 Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState stateService play Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState currentStatus play Feb 10 23:37:14 volumio volumio[6670]: info: Received an update from plugin. extracting info from payload Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: ------------------------------ 252ms Feb 10 23:37:14 volumio volumio[6670]: info: ------------------------------ 250ms Feb 10 23:37:14 volumio volumio[6670]: info: ------------------------------ 227ms Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: sendMpdCommand playlistinfo took 159 milliseconds Feb 10 23:37:14 volumio volumio[6670]: verbose: ControllerMpd::parseTrackInfo Feb 10 23:37:14 volumio volumio[6670]: info: ControllerMpd::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::servicePushState Feb 10 23:37:14 volumio volumio[6670]: verbose: In UPNP mode Feb 10 23:37:14 volumio volumio[6670]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"640 Kbps","isStreaming":false,"title":"White Flag","artist":"Dido","album":"Life for Rent","uri":"http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac","trackType":"flac"} Feb 10 23:37:14 volumio volumio[6670]: verbose: CURRENT POSITION 0 Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState stateService play Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::syncState currentStatus play Feb 10 23:37:14 volumio volumio[6670]: info: Received an update from plugin. extracting info from payload Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:14 volumio volumio[6670]: info: ------------------------------ 316ms Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreCommandRouter::volumioGetQueue Feb 10 23:37:14 volumio volumio[6670]: info: CoreStateMachine::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: CorePlayQueue::getQueue Feb 10 23:37:14 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:14 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:14 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:14 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:14 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:15 volumio volumio[6670]: info: Clearing queue after UPNP request Feb 10 23:37:16 volumio volumio[6670]: info: CoreStateMachine::ClearQueue Feb 10 23:37:16 volumio volumio[6670]: info: CoreStateMachine::stop Feb 10 23:37:16 volumio volumio[6670]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 23:37:16 volumio volumio[6670]: info: CoreStateMachine::stPlaybackTimer Feb 10 23:37:16 volumio volumio[6670]: info: CoreStateMachine::updateTrackBlock Feb 10 23:37:16 volumio volumio[6670]: info: CorePlayQueue::getTrackBlock Feb 10 23:37:16 volumio volumio[6670]: info: CoreStateMachine::pushState Feb 10 23:37:16 volumio volumio[6670]: info: CorePlayQueue::getTrack 0 Feb 10 23:37:16 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 23:37:16 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:16 volumio volumio[6670]: info: CoreStateMachine::serviceStop Feb 10 23:37:16 volumio volumio[6670]: info: CorePlayQueue::getTrack 0 Feb 10 23:37:16 volumio volumio[6670]: info: ControllerMpd::stop Feb 10 23:37:16 volumio volumio[6670]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 23:37:16 volumio volumio[6670]: info: CorePlayQueue::clearPlayQueue Feb 10 23:37:16 volumio volumio[6670]: info: CorePlayQueue::saveQueue Feb 10 23:37:16 volumio volumio[6670]: info: CoreCommandRouter::volumioPushState Feb 10 23:37:16 volumio volumio[6670]: info: CoreCommandRouter::volumioPushQueue Feb 10 23:37:16 volumio mpd[6843]: player: played "http://192.168.1.210:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I17842699.flac" Feb 10 23:37:16 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:20 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:20 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:20 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:20 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:20 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:20 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:24 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:25 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:25 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:25 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:25 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:25 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:26 volumio systemd[1]: mpd.service: Watchdog timeout (limit 20s)! Feb 10 23:37:26 volumio systemd[1]: mpd.service: Killing process 6843 (mpd) with signal SIGABRT. Feb 10 23:37:26 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Feb 10 23:37:26 volumio volumio[6670]: error: MPD error: Error: read ECONNRESET Feb 10 23:37:26 volumio systemd[1]: mpd.service: Failed with result 'watchdog'. Feb 10 23:37:26 volumio volumio[6670]: error: Upnp client error: Error: read ECONNRESET Feb 10 23:37:26 volumio systemd[1]: mpd.service: Consumed 14.520s CPU time. Feb 10 23:37:26 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 23:37:26 volumio sudo[8492]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 23:37:26 volumio sudo[8492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 23:37:26 volumio sudo[8492]: pam_unix(sudo:session): session closed for user root Feb 10 23:37:27 volumio mpd[8495]: 2026-02-10T23:37:27 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 23:37:27 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 23:37:28 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:30 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:30 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:30 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:30 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:30 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:31 volumio volumio[6670]: error: updateQueue error: null Feb 10 23:37:31 volumio sudo[7996]: pam_unix(sudo:session): session closed for user root Feb 10 23:37:31 volumio volumio[6670]: info: An error occurred mounting the new share. Rolling back configuration Feb 10 23:37:32 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:32 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 10 23:37:33 volumio sudo[8008]: pam_unix(sudo:session): session closed for user root Feb 10 23:37:33 volumio volumio[6670]: info: Cannot mount NAS nasf7c0d5 at system boot, trial number 3 ,retrying in 5 seconds Feb 10 23:37:35 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:35 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:35 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:35 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:35 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:36 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:38 volumio sudo[8542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.210:/roberto.controchiave@gmail.com /mnt/NAS/nasf7c0d5 Feb 10 23:37:38 volumio sudo[8542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 23:37:40 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:40 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:40 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:40 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:40 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:40 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:44 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:45 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:45 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:45 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:45 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:45 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:48 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:50 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:50 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:50 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:50 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:50 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:52 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:37:56 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:56 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:56 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:37:56 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:37:56 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:37:56 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:38:00 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:38:01 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:01 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:01 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:01 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:01 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:04 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:38:06 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:06 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:06 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:06 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:06 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:09 volumio volumio[6670]: info: CoreCommandRouter::volumioGetState Feb 10 23:38:09 volumio volumio[6670]: info: CorePlayQueue::getTrack 0 Feb 10 23:38:11 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:11 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:11 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:11 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:11 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:16 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:16 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:16 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:16 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:16 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:19 volumio sudo[8186]: pam_unix(sudo:session): session closed for user root Feb 10 23:38:19 volumio volumio[6670]: info: An error occurred mounting the new share. Rolling back configuration Feb 10 23:38:20 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 10 23:38:21 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:21 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:21 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:21 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:21 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:26 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:26 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:26 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:26 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:26 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 23:38:28 volumio volumio[6670]: info: Retrieving Cloud Streaming UI Feb 10 23:38:28 volumio volumio[6670]: info: Getting Tidal Cloud Configuration Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 23:38:28 volumio volumio[6670]: info: Getting Qobuz Cloud Configuration Feb 10 23:38:28 volumio volumio[6670]: info: Asking plugin for UI Config Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 23:38:28 volumio volumio[6670]: info: Getting Spotify Cloud Configuration Feb 10 23:38:28 volumio volumio[6670]: info: Asking plugin for UI Config Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 23:38:28 volumio volumio[6670]: info: Saving Spotify Acccount Feb 10 23:38:28 volumio volumio[6670]: info: Got Tidal Cloud Configuration Feb 10 23:38:28 volumio volumio[6670]: info: Got it Feb 10 23:38:28 volumio volumio[6670]: info: Got it Feb 10 23:38:28 volumio volumio[6670]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 10 23:38:28 volumio volumio[6670]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::volumioGetBrowseSources Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::volumioGetBrowseSources Feb 10 23:38:28 volumio volumio[6670]: info: CoreCommandRouter::volumioGetBrowseSources Feb 10 23:38:29 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:38:29 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 10 23:38:31 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:31 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:31 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:31 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:31 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:33 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:38:36 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Feb 10 23:38:37 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:37 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:37 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:37 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:37 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:37 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:38:37 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 10 23:38:41 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:38:42 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:42 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:42 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:42 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:42 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:43 volumio sudo[8542]: pam_unix(sudo:session): session closed for user root Feb 10 23:38:43 volumio volumio[6670]: info: Cannot mount NAS nasf7c0d5 at system boot, trial number 4 ,retrying in 5 seconds Feb 10 23:38:45 volumio volumio[6670]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 10 23:38:47 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:47 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:47 volumio volumio[6670]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 10 23:38:47 volumio volumio[6670]: info: Discovery: Restarting Browsing Feb 10 23:38:47 volumio volumio[6670]: info: Discovery: Restart already pending, ignoring duplicate call Feb 10 23:38:48 volumio volumio[6670]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 23:38:48 volumio volumio[6670]: TypeError: Cannot read properties of undefined (reading 'replace') Feb 10 23:38:48 volumio volumio[6670]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Feb 10 23:38:48 volumio volumio[6670]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Feb 10 23:38:48 volumio volumio[6670]: at listOnTimeout (node:internal/timers:573:17) Feb 10 23:38:48 volumio volumio[6670]: at process.processTimers (node:internal/timers:514:7) Feb 10 23:38:48 volumio volumio[6670]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 23:38:49 volumio sudo[8831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 23:37' Feb 10 23:38:49 volumio sudo[8831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"