-- Logs begin at Sun 2024-03-24 11:26:19 IST, end at Sun 2024-03-31 11:48:44 IST. -- Mar 31 11:47:36 volumio-diw volumio[744]: Searching plugin music_service/mpd Mar 31 11:47:36 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 31 11:47:36 volumio-diw volumio[744]: Searching plugin music_service/mpd Mar 31 11:47:36 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 31 11:47:38 volumio-diw volumio[744]: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:47:39 volumio-diw volumio[744]: info: All search sources collected, pushing search results Mar 31 11:47:39 volumio-diw volumio[744]: info: All search sources collected, pushing search results Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:39 volumio-diw volumio[744]: info: Prefetching next song Mar 31 11:47:39 volumio-diw volumio[744]: info: DOING PREFETCH IN MPD Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand add "NAS/SynologyNAS/_Download_Inprogress/downloaded/Meute - Empor [2024][24-88,2][Flac]/02 - Aurora.flac" Mar 31 11:47:39 volumio-diw volumio[744]: info: sendMpdCommand add "NAS/SynologyNAS/_Download_Inprogress/downloaded/Meute - Empor [2024][24-88,2][Flac]/02 - Aurora.flac" took 2 milliseconds Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 31 11:47:39 volumio-diw volumio[744]: info: Mar 31 11:47:39 volumio-diw volumio[744]: ---------------------------- MPD announces system playlist update Mar 31 11:47:39 volumio-diw volumio[744]: info: Ignoring MPD Status Update Mar 31 11:47:39 volumio-diw volumio[744]: info: Mar 31 11:47:39 volumio-diw volumio[744]: ---------------------------- MPD announces system playlist update Mar 31 11:47:39 volumio-diw volumio[744]: info: Ignoring MPD Status Update Mar 31 11:47:39 volumio-diw volumio[744]: info: Mar 31 11:47:39 volumio-diw volumio[744]: ---------------------------- MPD announces system playlist update Mar 31 11:47:39 volumio-diw volumio[744]: info: Ignoring MPD Status Update Mar 31 11:47:39 volumio-diw volumio[744]: info: Mar 31 11:47:39 volumio-diw volumio[744]: ---------------------------- MPD announces state update: options Mar 31 11:47:39 volumio-diw volumio[744]: info: sendMpdCommand consume 1 took 7 milliseconds Mar 31 11:47:39 volumio-diw volumio[744]: info: ControllerMpd::getState Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:47:39 volumio-diw volumio[744]: info: ------------------------------ 8ms Mar 31 11:47:39 volumio-diw volumio[744]: info: ------------------------------ 5ms Mar 31 11:47:39 volumio-diw volumio[744]: info: ------------------------------ 6ms Mar 31 11:47:39 volumio-diw volumio[744]: info: Mar 31 11:47:39 volumio-diw volumio[744]: ---------------------------- MPD announces state update: options Mar 31 11:47:39 volumio-diw volumio[744]: info: ControllerMpd::getState Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:47:39 volumio-diw volumio[744]: info: Mar 31 11:47:39 volumio-diw volumio[744]: ---------------------------- MPD announces state update: options Mar 31 11:47:39 volumio-diw volumio[744]: info: ControllerMpd::getState Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:47:39 volumio-diw volumio[744]: info: sendMpdCommand status took 6 milliseconds Mar 31 11:47:39 volumio-diw volumio[744]: info: sendMpdCommand status took 4 milliseconds Mar 31 11:47:39 volumio-diw volumio[744]: info: sendMpdCommand status took 3 milliseconds Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::parseState Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::parseState Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::parseState Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:47:39 volumio-diw volumio[744]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 31 11:47:39 volumio-diw volumio[744]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 31 11:47:39 volumio-diw volumio[744]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:47:39 volumio-diw volumio[744]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:47:39 volumio-diw volumio[744]: info: ControllerMpd::pushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::servicePushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378240,"duration":383,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2011 Kbps","isStreaming":false,"title":"Anti Loudness","artist":"Meute","album":"Empor","uri":"NAS/SynologyNAS/_Download_Inprogress/downloaded/Meute - Empor [2024][24-88,2][Flac]/03 - Anti Loudness.flac","trackType":"flac"} Mar 31 11:47:39 volumio-diw volumio[744]: verbose: CURRENT POSITION 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::syncState stateService play Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::syncState currentStatus play Mar 31 11:47:39 volumio-diw volumio[744]: info: Received an update from plugin. extracting info from payload Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:39 volumio-diw volumio[744]: info: ControllerMpd::pushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::servicePushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378240,"duration":383,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2011 Kbps","isStreaming":false,"title":"Anti Loudness","artist":"Meute","album":"Empor","uri":"NAS/SynologyNAS/_Download_Inprogress/downloaded/Meute - Empor [2024][24-88,2][Flac]/03 - Anti Loudness.flac","trackType":"flac"} Mar 31 11:47:39 volumio-diw volumio[744]: verbose: CURRENT POSITION 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::syncState stateService play Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::syncState currentStatus play Mar 31 11:47:39 volumio-diw volumio[744]: info: Received an update from plugin. extracting info from payload Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:39 volumio-diw volumio[744]: info: ControllerMpd::pushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::servicePushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378240,"duration":383,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2011 Kbps","isStreaming":false,"title":"Anti Loudness","artist":"Meute","album":"Empor","uri":"NAS/SynologyNAS/_Download_Inprogress/downloaded/Meute - Empor [2024][24-88,2][Flac]/03 - Anti Loudness.flac","trackType":"flac"} Mar 31 11:47:39 volumio-diw volumio[744]: verbose: CURRENT POSITION 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::syncState stateService play Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::syncState currentStatus play Mar 31 11:47:39 volumio-diw volumio[744]: info: Received an update from plugin. extracting info from payload Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:39 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 2 Mar 31 11:47:39 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:39 volumio-diw volumio[744]: info: ------------------------------ 68ms Mar 31 11:47:39 volumio-diw volumio[744]: info: ------------------------------ 65ms Mar 31 11:47:39 volumio-diw volumio[744]: info: ------------------------------ 65ms Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::startPlaybackTimer Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: Mar 31 11:47:44 volumio-diw volumio[744]: ---------------------------- MPD announces system playlist update Mar 31 11:47:44 volumio-diw volumio[744]: info: Ignoring MPD Status Update Mar 31 11:47:44 volumio-diw volumio[744]: info: Mar 31 11:47:44 volumio-diw volumio[744]: ---------------------------- MPD announces state update: player Mar 31 11:47:44 volumio-diw volumio[744]: info: ControllerMpd::getState Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:47:44 volumio-diw volumio[744]: info: Mar 31 11:47:44 volumio-diw volumio[744]: ---------------------------- MPD announces system playlist update Mar 31 11:47:44 volumio-diw volumio[744]: info: Ignoring MPD Status Update Mar 31 11:47:44 volumio-diw volumio[744]: info: Mar 31 11:47:44 volumio-diw volumio[744]: ---------------------------- MPD announces state update: player Mar 31 11:47:44 volumio-diw volumio[744]: info: ControllerMpd::getState Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:47:44 volumio-diw volumio[744]: info: Mar 31 11:47:44 volumio-diw volumio[744]: ---------------------------- MPD announces system playlist update Mar 31 11:47:44 volumio-diw volumio[744]: info: Ignoring MPD Status Update Mar 31 11:47:44 volumio-diw volumio[744]: info: Mar 31 11:47:44 volumio-diw volumio[744]: ---------------------------- MPD announces state update: player Mar 31 11:47:44 volumio-diw volumio[744]: info: ControllerMpd::getState Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand status Mar 31 11:47:44 volumio-diw volumio[744]: info: ------------------------------ 6ms Mar 31 11:47:44 volumio-diw volumio[744]: info: sendMpdCommand status took 4 milliseconds Mar 31 11:47:44 volumio-diw volumio[744]: info: ------------------------------ 5ms Mar 31 11:47:44 volumio-diw volumio[744]: info: sendMpdCommand status took 4 milliseconds Mar 31 11:47:44 volumio-diw volumio[744]: info: ------------------------------ 3ms Mar 31 11:47:44 volumio-diw volumio[744]: info: sendMpdCommand status took 3 milliseconds Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::parseState Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::parseState Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::parseState Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 11:47:44 volumio-diw volumio[744]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 31 11:47:44 volumio-diw volumio[744]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 31 11:47:44 volumio-diw volumio[744]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:47:44 volumio-diw volumio[744]: verbose: ControllerMpd::parseTrackInfo Mar 31 11:47:44 volumio-diw volumio[744]: info: ControllerMpd::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::servicePushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":321,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2119 Kbps","isStreaming":false,"title":"Aurora","artist":"Meute","album":"Empor","uri":"NAS/SynologyNAS/_Download_Inprogress/downloaded/Meute - Empor [2024][24-88,2][Flac]/02 - Aurora.flac","trackType":"flac"} Mar 31 11:47:44 volumio-diw volumio[744]: verbose: CURRENT POSITION 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::syncState stateService play Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::syncState currentStatus play Mar 31 11:47:44 volumio-diw volumio[744]: info: Received an update from plugin. extracting info from payload Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:44 volumio-diw volumio[744]: info: ControllerMpd::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::servicePushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":321,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2119 Kbps","isStreaming":false,"title":"Aurora","artist":"Meute","album":"Empor","uri":"NAS/SynologyNAS/_Download_Inprogress/downloaded/Meute - Empor [2024][24-88,2][Flac]/02 - Aurora.flac","trackType":"flac"} Mar 31 11:47:44 volumio-diw volumio[744]: verbose: CURRENT POSITION 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::syncState stateService play Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::syncState currentStatus play Mar 31 11:47:44 volumio-diw volumio[744]: info: Received an update from plugin. extracting info from payload Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:44 volumio-diw volumio[744]: info: ControllerMpd::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::servicePushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":321,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2119 Kbps","isStreaming":false,"title":"Aurora","artist":"Meute","album":"Empor","uri":"NAS/SynologyNAS/_Download_Inprogress/downloaded/Meute - Empor [2024][24-88,2][Flac]/02 - Aurora.flac","trackType":"flac"} Mar 31 11:47:44 volumio-diw volumio[744]: verbose: CURRENT POSITION 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::syncState stateService play Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::syncState currentStatus play Mar 31 11:47:44 volumio-diw volumio[744]: info: Received an update from plugin. extracting info from payload Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:44 volumio-diw volumio[744]: info: ------------------------------ 64ms Mar 31 11:47:44 volumio-diw volumio[744]: info: ------------------------------ 63ms Mar 31 11:47:44 volumio-diw volumio[744]: info: ------------------------------ 62ms Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreStateMachine::pushState Mar 31 11:47:44 volumio-diw volumio[744]: info: CorePlayQueue::getTrack 1 Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:47:44 volumio-diw volumio[744]: info: CoreCommandRouter::volumioPushState Mar 31 11:47:57 volumio-diw volumio[744]: Searching plugin music_service/mpd Mar 31 11:47:57 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 31 11:47:58 volumio-diw volumio[744]: Searching plugin music_service/mpd Mar 31 11:47:58 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 31 11:47:59 volumio-diw volumio[744]: Searching plugin music_service/mpd Mar 31 11:47:59 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 31 11:47:59 volumio-diw volumio[744]: Searching plugin music_service/mpd Mar 31 11:47:59 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 31 11:48:01 volumio-diw ntpd[632]: 162.159.200.123 local addr 192.168.2.25 -> Mar 31 11:48:02 volumio-diw volumio[744]: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:48:03 volumio-diw volumio[744]: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:48:03 volumio-diw volumio[744]: Searching plugin music_service/mpd Mar 31 11:48:03 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 31 11:48:06 volumio-diw volumio[744]: Searching plugin music_service/mpd Mar 31 11:48:06 volumio-diw volumio[744]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:10 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:16 volumio-diw volumio[744]: Cannot compose Albumart path Mar 31 11:48:17 volumio-diw volumio[744]: info: All search sources collected, pushing search results Mar 31 11:48:18 volumio-diw volumio[744]: info: All search sources collected, pushing search results Mar 31 11:48:18 volumio-diw volumio[744]: info: All search sources collected, pushing search results Mar 31 11:48:18 volumio-diw volumio[744]: info: All search sources collected, pushing search results Mar 31 11:48:18 volumio-diw volumio[744]: info: All search sources collected, pushing search results Mar 31 11:48:18 volumio-diw volumio[744]: info: All search sources collected, pushing search results Mar 31 11:48:18 volumio-diw volumio[744]: Upnp client error: Error: This socket has been ended by the other party Mar 31 11:48:19 volumio-diw dhcpcd[803]: eth0: carrier lost Mar 31 11:48:19 volumio-diw kernel: bcmgenet fd580000.ethernet eth0: Link is Down Mar 31 11:48:19 volumio-diw avahi-daemon[511]: Withdrawing address record for 192.168.2.25 on eth0. Mar 31 11:48:19 volumio-diw avahi-daemon[511]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.2.25. Mar 31 11:48:19 volumio-diw avahi-daemon[511]: Interface eth0.IPv4 no longer relevant for mDNS. Mar 31 11:48:19 volumio-diw dhcpcd[803]: eth0: deleting route to 192.168.2.0/24 Mar 31 11:48:19 volumio-diw dhcpcd[803]: eth0: deleting default route via 192.168.2.1 Mar 31 11:48:19 volumio-diw volumio[744]: info: Discovery: A device disappeared from network Mar 31 11:48:19 volumio-diw volumio[744]: info: Discovery: Device volumio-diw disappeared from network Mar 31 11:48:19 volumio-diw dnsmasq[735]: reading /etc/resolv.conf Mar 31 11:48:19 volumio-diw dnsmasq[735]: using nameserver 208.67.222.222#53 Mar 31 11:48:19 volumio-diw dnsmasq[735]: using nameserver 208.67.220.220#53 Mar 31 11:48:19 volumio-diw ifplugd(eth0)[640]: Link beat lost. Mar 31 11:48:20 volumio-diw ntpd[632]: Deleting interface #5 eth0, 192.168.2.25#123, interface stats: received=62, sent=79, dropped=0, active_time=91 secs Mar 31 11:48:20 volumio-diw ntpd[632]: 192.46.211.253 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 172.232.97.196 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 143.244.134.227 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 20.219.106.174 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 192.46.212.117 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 144.24.146.96 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 164.100.255.122 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 162.159.200.1 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 139.84.142.141 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 172.105.60.167 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 13.126.27.131 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 139.59.15.185 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 157.245.102.2 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 95.216.192.15 local addr 192.168.2.25 -> Mar 31 11:48:20 volumio-diw ntpd[632]: 95.216.144.226 local addr 192.168.2.25 -> Mar 31 11:48:22 volumio-diw dhcpcd[803]: eth0: carrier acquired Mar 31 11:48:22 volumio-diw dhcpcd[803]: eth0: IAID 32:23:8c:62 Mar 31 11:48:22 volumio-diw kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Mar 31 11:48:22 volumio-diw dhcpcd[803]: eth0: soliciting an IPv6 router Mar 31 11:48:22 volumio-diw ifplugd(eth0)[640]: Link beat detected. Mar 31 11:48:22 volumio-diw dhcpcd[803]: eth0: rebinding lease of 192.168.2.25 Mar 31 11:48:23 volumio-diw dhcpcd[803]: eth0: probing address 192.168.2.25/24 Mar 31 11:48:23 volumio-diw volumio[744]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 11:48:23 volumio-diw volumio[744]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Mar 31 11:48:23 volumio-diw volumio[744]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Mar 31 11:48:23 volumio-diw volumio[744]: errno: -3007, Mar 31 11:48:23 volumio-diw volumio[744]: code: 'ENOTFOUND', Mar 31 11:48:23 volumio-diw volumio[744]: syscall: 'getaddrinfo', Mar 31 11:48:23 volumio-diw volumio[744]: hostname: 'lastfm.freetls.fastly.net' Mar 31 11:48:23 volumio-diw volumio[744]: } Mar 31 11:48:23 volumio-diw volumio[744]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 11:48:23 volumio-diw sudo[3106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 11:47 Mar 31 11:48:23 volumio-diw sudo[3106]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:48:23 volumio-diw sudo[3106]: pam_unix(sudo:session): session closed for user root Mar 31 11:48:23 volumio-diw volumio-remote-updater[506]: [2024-03-31 11:48:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 31 11:48:23 volumio-diw volumio-remote-updater[506]: [2024-03-31 11:48:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 31 11:48:23 volumio-diw go-librespot[976]: time="2024-03-31T11:48:23+05:30" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Mar 31 11:48:23 volumio-diw systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 31 11:48:23 volumio-diw systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 31 11:48:23 volumio-diw systemd[1]: Started dynamicswap service. Mar 31 11:48:23 volumio-diw systemd[1]: dynamicswap.service: Succeeded. Mar 31 11:48:24 volumio-diw systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 31 11:48:24 volumio-diw systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 31 11:48:24 volumio-diw systemd[1]: Started dynamicswap service. Mar 31 11:48:24 volumio-diw systemd[1]: Stopped Volumio Backend Module. Mar 31 11:48:24 volumio-diw systemd[1]: Started Volumio Backend Module. Mar 31 11:48:24 volumio-diw systemd[1]: dynamicswap.service: Succeeded. Mar 31 11:48:25 volumio-diw volumio[3122]: info: ------------------------------------------- Mar 31 11:48:25 volumio-diw volumio[3122]: info: ----- Volumio3 ---- Mar 31 11:48:25 volumio-diw volumio[3122]: info: ------------------------------------------- Mar 31 11:48:25 volumio-diw volumio[3122]: info: ----- System startup ---- Mar 31 11:48:25 volumio-diw volumio[3122]: info: ------------------------------------------- Mar 31 11:48:25 volumio-diw volumio[3122]: info: MYVOLUMIO Environment detected Mar 31 11:48:25 volumio-diw volumio[3122]: info: Plugin folders cleanup Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning into folder /volumio/app/plugins/ Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning category audio_interface Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning category miscellanea Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning category music_service Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning category plugins.json Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning category system_controller Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning category user_interface Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning into folder /data/plugins/ Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning category music_service Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning category system_controller Mar 31 11:48:25 volumio-diw volumio[3122]: info: Scanning category user_interface Mar 31 11:48:25 volumio-diw volumio[3122]: info: Plugin folders cleanup completed Mar 31 11:48:25 volumio-diw volumio[3122]: info: ------------------------------------------- Mar 31 11:48:25 volumio-diw volumio[3122]: info: ----- Core plugins startup ---- Mar 31 11:48:25 volumio-diw volumio[3122]: info: ------------------------------------------- Mar 31 11:48:25 volumio-diw volumio[3122]: info: Loading plugins from folder /volumio/app/plugins/ Mar 31 11:48:25 volumio-diw volumio[3122]: info: Adding plugin upnp to MyMusic Plugins Mar 31 11:48:25 volumio-diw volumio[3122]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 31 11:48:25 volumio-diw volumio[3122]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 31 11:48:25 volumio-diw volumio[3122]: info: Loading plugins from folder /data/plugins/ Mar 31 11:48:25 volumio-diw volumio[3122]: info: Loading plugin "system"... Mar 31 11:48:25 volumio-diw volumio[3122]: info: Loading plugin "appearance"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "network"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Refreshing Cached IP Addresses Mar 31 11:48:26 volumio-diw sudo[3148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 11:48:26 volumio-diw sudo[3148]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:48:26 volumio-diw sudo[3148]: pam_unix(sudo:session): session closed for user root Mar 31 11:48:26 volumio-diw sudo[3150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "services"... Mar 31 11:48:26 volumio-diw sudo[3150]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "alsa_controller"... Mar 31 11:48:26 volumio-diw sudo[3150]: pam_unix(sudo:session): session closed for user root Mar 31 11:48:26 volumio-diw sudo[3153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 31 11:48:26 volumio-diw sudo[3153]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:48:26 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "wizard"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "networkfs"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Cannot mount NAS SynologyNAS at system boot, trial number 1 ,retrying in 5 seconds Mar 31 11:48:26 volumio-diw volumio[3122]: info: Starting Udev Watcher for removable devices Mar 31 11:48:26 volumio-diw volumio[3122]: info: Ignoring mount for partition: boot Mar 31 11:48:26 volumio-diw volumio[3122]: info: Ignoring mount for partition: volumio Mar 31 11:48:26 volumio-diw volumio[3122]: info: Ignoring mount for partition: volumio_data Mar 31 11:48:26 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "volumio_command_line_client"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "upnp"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: [1711865906277] Starting Upmpd Daemon Mar 31 11:48:26 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "my_music"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "mpd"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "upnp_browser"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "alarm-clock"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "airplay_emulation"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Starting Shairport Sync Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "last_100"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "webradio"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "i2s_dacs"... Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "volumiodiscovery"... Mar 31 11:48:26 volumio-diw volumio[3122]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 31 11:48:26 volumio-diw volumio[3122]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 31 11:48:26 volumio-diw node[3122]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 31 11:48:26 volumio-diw volumio[3122]: *** WARNING *** For more information see Mar 31 11:48:26 volumio-diw volumio[3122]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 31 11:48:26 volumio-diw volumio[3122]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 31 11:48:26 volumio-diw volumio[3122]: *** WARNING *** For more information see Mar 31 11:48:26 volumio-diw node[3122]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 31 11:48:26 volumio-diw node[3122]: *** WARNING *** For more information see Mar 31 11:48:26 volumio-diw node[3122]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 31 11:48:26 volumio-diw node[3122]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 31 11:48:26 volumio-diw node[3122]: *** WARNING *** For more information see Mar 31 11:48:26 volumio-diw volumio[3122]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 31 11:48:26 volumio-diw volumio[3122]: info: Discovery: Started advertising with name: Volumio-diw Mar 31 11:48:26 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 31 11:48:26 volumio-diw volumio[3122]: info: Loading plugin "spop"... Mar 31 11:48:27 volumio-diw volumio[3122]: info: Loading plugin "ytcr"... Mar 31 11:48:28 volumio-diw volumio[3122]: info: Loading plugin "ytmusic"... Mar 31 11:48:28 volumio-diw volumio-remote-updater[506]: [2024-03-31 11:48:28] [connect] Successful connection Mar 31 11:48:28 volumio-diw dhcpcd[803]: eth0: leased 192.168.2.25 for 86400 seconds Mar 31 11:48:28 volumio-diw dhcpcd[803]: eth0: adding route to 192.168.2.0/24 Mar 31 11:48:28 volumio-diw dhcpcd[803]: eth0: adding default route via 192.168.2.1 Mar 31 11:48:28 volumio-diw avahi-daemon[511]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.25. Mar 31 11:48:28 volumio-diw avahi-daemon[511]: New relevant interface eth0.IPv4 for mDNS. Mar 31 11:48:28 volumio-diw avahi-daemon[511]: Registering new address record for 192.168.2.25 on eth0.IPv4. Mar 31 11:48:28 volumio-diw dnsmasq[735]: reading /etc/resolv.conf Mar 31 11:48:28 volumio-diw dnsmasq[735]: using nameserver 8.8.8.8#53 Mar 31 11:48:28 volumio-diw dnsmasq[735]: using nameserver 192.168.1.254#53 Mar 31 11:48:28 volumio-diw dnsmasq[735]: using nameserver 192.168.2.1#53 Mar 31 11:48:28 volumio-diw dnsmasq[735]: using nameserver 208.67.222.222#53 Mar 31 11:48:28 volumio-diw dnsmasq[735]: using nameserver 208.67.220.220#53 Mar 31 11:48:29 volumio-diw volumio[3122]: info: Loading plugin "autostart"... Mar 31 11:48:29 volumio-diw volumio[3122]: info: Applying required configuration parameters for plugin autostart Mar 31 11:48:29 volumio-diw volumio[3122]: info: AutoStart - onVolumioStart - read config.json Mar 31 11:48:29 volumio-diw volumio[3122]: info: Loading plugin "outputs"... Mar 31 11:48:29 volumio-diw volumio[3122]: info: Loading plugin "albumart"... Mar 31 11:48:29 volumio-diw volumio[3122]: info: Plugin example_plugin is not enabled Mar 31 11:48:29 volumio-diw volumio[3122]: info: Loading plugin "inputs"... Mar 31 11:48:29 volumio-diw volumio[3122]: info: Loading plugin "updater_comm"... Mar 31 11:48:29 volumio-diw volumio[3122]: info: Plugin mpdemulation is not enabled Mar 31 11:48:29 volumio-diw volumio[3122]: info: Loading plugin "rest_api"... Mar 31 11:48:29 volumio-diw volumio[3122]: info: Loading plugin "websocket"... Mar 31 11:48:29 volumio-diw volumio[3122]: info: Loading plugin "backup_restore"... Mar 31 11:48:29 volumio-diw volumio[3122]: Forking 3 albumart workers Mar 31 11:48:29 volumio-diw volumio[3122]: info: Applying required configuration parameters for plugin backup_restore Mar 31 11:48:29 volumio-diw volumio[3122]: info: Loading plugin "randomizer"... Mar 31 11:48:29 volumio-diw sudo[3153]: pam_unix(sudo:session): session closed for user root Mar 31 11:48:30 volumio-diw volumio[3122]: info: Applying required configuration parameters for plugin randomizer Mar 31 11:48:30 volumio-diw volumio[3122]: info: Loading i18n strings for locale en Mar 31 11:48:30 volumio-diw volumio[3122]: Updating browse sources language Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::initPlayerControls Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: Express server listening on port 3000 Mar 31 11:48:30 volumio-diw volumio[3122]: [Metrics] WebUI: 5s 384.10ms Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreStateMachine::resetVolumioState Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreStateMachine::getcurrentVolume Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioRetrievevolume Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreStateMachine::pushState Mar 31 11:48:30 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioPushState Mar 31 11:48:30 volumio-diw volumio-remote-updater[506]: [2024-03-31 11:48:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711865908 101 Mar 31 11:48:30 volumio-diw volumio[3122]: Starting albumart workers Mar 31 11:48:30 volumio-diw volumio[3122]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 31 11:48:30 volumio-diw volumio[3122]: Starting albumart workers Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 31 11:48:30 volumio-diw volumio[3122]: info: Reloading queue from file Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreStateMachine::setRepeat null single undefined Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreStateMachine::pushState Mar 31 11:48:30 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioPushState Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreStateMachine::setRandom true Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreStateMachine::pushState Mar 31 11:48:30 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioPushState Mar 31 11:48:30 volumio-diw volumio[3122]: Starting albumart workers Mar 31 11:48:30 volumio-diw volumio[3122]: info: Setting Device type: Raspberry PI Mar 31 11:48:30 volumio-diw volumio[3122]: info: Discovery: adding 79e6ffb4-5937-4a70-ad15-fe02916cfbb5 Mar 31 11:48:30 volumio-diw volumio[3122]: info: Discovery: Found device Volumio-diw Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetState Mar 31 11:48:30 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:30 volumio-diw volumio[3122]: info: Discovery: this is already registered, 79e6ffb4-5937-4a70-ad15-fe02916cfbb5 Mar 31 11:48:30 volumio-diw volumio[3122]: info: Discovery: Found device Volumio-diw Mar 31 11:48:30 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetState Mar 31 11:48:30 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:30 volumio-diw ntpd[632]: Listen normally on 6 eth0 192.168.2.25:123 Mar 31 11:48:30 volumio-diw ntpd[632]: new interface(s) found: waking up resolver Mar 31 11:48:31 volumio-diw volumio[3122]: info: Cannot mount NAS SynologyNAS at system boot, trial number 2 ,retrying in 5 seconds Mar 31 11:48:31 volumio-diw volumio[3122]: verbose: New Socket.io Connection to 192.168.2.25 from 192.168.2.33 UA: unknown Total Clients: 2 Mar 31 11:48:31 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetState Mar 31 11:48:31 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:31 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 31 11:48:31 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 31 11:48:31 volumio-diw ntpd[632]: 162.159.200.1 local addr 192.168.2.25 -> Mar 31 11:48:31 volumio-diw ntpd[632]: 20.219.106.174 local addr 192.168.2.25 -> Mar 31 11:48:31 volumio-diw ntpd[632]: 143.244.134.227 local addr 192.168.2.25 -> Mar 31 11:48:36 volumio-diw volumio[3122]: info: Cannot mount NAS SynologyNAS at system boot, trial number 3 ,retrying in 5 seconds Mar 31 11:48:36 volumio-diw sudo[3282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 11:48:36 volumio-diw sudo[3282]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:48:36 volumio-diw sudo[3282]: pam_unix(sudo:session): session closed for user root Mar 31 11:48:36 volumio-diw sudo[3285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 11:48:36 volumio-diw sudo[3285]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:48:36 volumio-diw sudo[3285]: pam_unix(sudo:session): session closed for user root Mar 31 11:48:36 volumio-diw sudo[3288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 31 11:48:36 volumio-diw sudo[3288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 11:48:36 volumio-diw sudo[3288]: pam_unix(sudo:session): session closed for user root Mar 31 11:48:36 volumio-diw volumio[3122]: info: Upmpdcli Daemon Started Mar 31 11:48:39 volumio-diw volumio[3122]: verbose: New Socket.io Connection to 192.168.2.25 from 192.168.2.33 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 3 Mar 31 11:48:39 volumio-diw volumio[3122]: verbose: New Socket.io Connection to 192.168.2.25 from 192.168.2.33 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 4 Mar 31 11:48:40 volumio-diw volumio[3122]: info: Received Get System Info Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 11:48:40 volumio-diw volumio[3122]: info: Discovery: Getting this device information Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetState Mar 31 11:48:40 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetState Mar 31 11:48:40 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetVisibleSources Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetState Mar 31 11:48:40 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 31 11:48:40 volumio-diw volumio[3122]: info: Received Get System Info Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 11:48:40 volumio-diw volumio[3122]: info: Discovery: Getting this device information Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetState Mar 31 11:48:40 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetState Mar 31 11:48:40 volumio-diw volumio[3122]: info: CorePlayQueue::getTrack 0 Mar 31 11:48:40 volumio-diw volumio[3122]: info: Listing playlists Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreCommandRouter::volumioGetQueue Mar 31 11:48:40 volumio-diw volumio[3122]: info: CoreStateMachine::getQueue Mar 31 11:48:40 volumio-diw volumio[3122]: info: CorePlayQueue::getQueue Mar 31 11:48:41 volumio-diw volumio[3122]: info: Cannot mount NAS SynologyNAS at system boot, trial number 4 ,retrying in 5 seconds Mar 31 11:48:44 volumio-diw volumio[3122]: Searching plugin music_service/mpd Mar 31 11:48:44 volumio-diw volumio[3122]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 31 11:48:44 volumio-diw volumio[3122]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 11:48:44 volumio-diw volumio[3122]: TypeError: Cannot read property 'then' of undefined Mar 31 11:48:44 volumio-diw volumio[3122]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1582:17) Mar 31 11:48:44 volumio-diw volumio[3122]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Mar 31 11:48:44 volumio-diw volumio[3122]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:477:37) Mar 31 11:48:44 volumio-diw volumio[3122]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Mar 31 11:48:44 volumio-diw volumio[3122]: at Socket.emit (events.js:315:20) Mar 31 11:48:44 volumio-diw volumio[3122]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 31 11:48:44 volumio-diw volumio[3122]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 31 11:48:44 volumio-diw volumio[3122]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 11:48:44 volumio-diw sudo[3299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 11:47 Mar 31 11:48:44 volumio-diw sudo[3299]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"