-- 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"