-- Logs begin at Sun 2024-09-01 02:28:52 CEST, end at Sun 2024-09-01 02:34:53 CEST. -- Sep 01 02:34:06 volumioandrea volumio[858]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 01 02:34:07 volumioandrea volumio[858]: info: Preload queue cleared Sep 01 02:34:14 volumioandrea volumio[858]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 01 02:34:18 volumioandrea volumio[858]: info: Preload queue cleared Sep 01 02:34:18 volumioandrea volumio[858]: info: Preloading song: upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@11b50cf64e08709398d9 Sep 01 02:34:18 volumioandrea volumio[858]: info: Preloading song: upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@b18880da72a2bbc6cf98 Sep 01 02:34:18 volumioandrea volumio[858]: info: Preloading song: upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@435bbc1a6b6c0773b997 Sep 01 02:34:18 volumioandrea volumio[858]: info: Preloading song: upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@1d90f57c7d94b0539880 Sep 01 02:34:18 volumioandrea volumio[858]: info: Preloading song: upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@60da059977c6fa4077d9 Sep 01 02:34:18 volumioandrea volumio[858]: info: Preloading song: upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@daad811effdb83818256 Sep 01 02:34:18 volumioandrea volumio[858]: info: Preloading song: upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@fd6407b20cdca5c22b93 Sep 01 02:34:18 volumioandrea volumio[858]: info: Preloading song: upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@63bb6584233d45a16087 Sep 01 02:34:18 volumioandrea volumio[858]: info: Preloading song: upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@772be2adfb8dcf1bdab8 Sep 01 02:34:19 volumioandrea volumio[858]: info: Exploding uri upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@11b50cf64e08709398d9 in service upnp_browser Sep 01 02:34:19 volumioandrea volumio[858]: info: Exploding uri upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@b18880da72a2bbc6cf98 in service upnp_browser Sep 01 02:34:19 volumioandrea volumio[858]: info: Exploding uri upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@435bbc1a6b6c0773b997 in service upnp_browser Sep 01 02:34:19 volumioandrea volumio[858]: info: Exploding uri upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@1d90f57c7d94b0539880 in service upnp_browser Sep 01 02:34:19 volumioandrea volumio[858]: info: Exploding uri upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@60da059977c6fa4077d9 in service upnp_browser Sep 01 02:34:19 volumioandrea volumio[858]: info: Exploding uri upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@daad811effdb83818256 in service upnp_browser Sep 01 02:34:19 volumioandrea volumio[858]: info: Exploding uri upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@fd6407b20cdca5c22b93 in service upnp_browser Sep 01 02:34:19 volumioandrea volumio[858]: info: Exploding uri upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@63bb6584233d45a16087 in service upnp_browser Sep 01 02:34:19 volumioandrea volumio[858]: info: Exploding uri upnp/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@772be2adfb8dcf1bdab8 in service upnp_browser Sep 01 02:34:29 volumioandrea volumio[858]: info: Preload queue cleared Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::ClearQueue Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::stop Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 02:34:29 volumioandrea volumio[858]: info: CorePlayQueue::clearPlayQueue Sep 01 02:34:29 volumioandrea volumio[858]: info: CorePlayQueue::saveQueue Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushQueue Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::addQueueItems Sep 01 02:34:29 volumioandrea volumio[858]: info: CorePlayQueue::addQueueItems Sep 01 02:34:29 volumioandrea volumio[858]: info: Preload queue cleared Sep 01 02:34:29 volumioandrea volumio[858]: info: Adding Item to queue: upnp/folder/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@62cf97a2fbe41ab4607a Sep 01 02:34:29 volumioandrea volumio[858]: info: Exploding uri upnp/folder/http://192.168.178.30:32469/ContentDirectory/1762bd68-11b3-b88b-2991-1995a8a5fbe7/control.xml@62cf97a2fbe41ab4607a in service upnp_browser Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushQueue Sep 01 02:34:29 volumioandrea volumio[858]: info: CorePlayQueue::saveQueue Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::updateTrackBlock Sep 01 02:34:29 volumioandrea volumio[858]: info: CorePlayQueue::getTrackBlock Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPlay Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::play index 0 Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::stop Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::play index undefined Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 02:34:29 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:29 volumioandrea volumio[858]: info: CoreStateMachine::startPlaybackTimer Sep 01 02:34:29 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:29 volumioandrea volumio[858]: info: [1725150869987] ControllerUPNPBrowser::clearAddPlayTrack Sep 01 02:34:29 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand stop took 12 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces system playlist update Sep 01 02:34:30 volumioandrea volumio[858]: info: Ignoring MPD Status Update Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand clear took 4 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3" Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces system playlist update Sep 01 02:34:30 volumioandrea volumio[858]: info: Ignoring MPD Status Update Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces system playlist update Sep 01 02:34:30 volumioandrea volumio[858]: info: Ignoring MPD Status Update Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces system playlist update Sep 01 02:34:30 volumioandrea volumio[858]: info: Ignoring MPD Status Update Sep 01 02:34:30 volumioandrea volumio[858]: error: updateQueue error: null Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 14ms Sep 01 02:34:30 volumioandrea volumio[858]: error: Upnp client error: Error: This socket has been ended by the other party Sep 01 02:34:30 volumioandrea volumio[858]: error: updateQueue error: null Sep 01 02:34:30 volumioandrea volumio[858]: error: updateQueue error: null Sep 01 02:34:30 volumioandrea volumio[858]: error: updateQueue error: null Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3" Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 401ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 400ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 398ms Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces system playlist update Sep 01 02:34:30 volumioandrea volumio[858]: info: Ignoring MPD Status Update Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand add "http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3" took 4 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand play Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces system playlist update Sep 01 02:34:30 volumioandrea volumio[858]: info: Ignoring MPD Status Update Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces system playlist update Sep 01 02:34:30 volumioandrea volumio[858]: info: Ignoring MPD Status Update Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces system playlist update Sep 01 02:34:30 volumioandrea volumio[858]: info: Ignoring MPD Status Update Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 14ms Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand play took 12 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 10ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 9ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 7ms Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces state update: player Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::getState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand status Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces state update: player Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::getState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand status Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces state update: player Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::getState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand status Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces state update: player Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::getState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand status Sep 01 02:34:30 volumioandrea volumio[858]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 01 02:34:30 volumioandrea volumio[858]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 01 02:34:30 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:30 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces state update: player Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand status took 84 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::getState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand status Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand status took 85 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand status took 85 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand status took 82 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces state update: player Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::getState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand status Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces state update: player Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::getState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand status Sep 01 02:34:30 volumioandrea volumio[858]: info: Sep 01 02:34:30 volumioandrea volumio[858]: ---------------------------- MPD announces state update: player Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::getState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand status Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand status took 51 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand playlistinfo took 41 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand playlistinfo took 38 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand playlistinfo took 37 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand playlistinfo took 36 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand status took 27 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand status took 22 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand status took 17 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseTrackInfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseTrackInfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseTrackInfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseTrackInfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseState Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::servicePushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:30 volumioandrea volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3","trackType":"mp3"} Sep 01 02:34:30 volumioandrea volumio[858]: verbose: CURRENT POSITION 0 Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState stateService play Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState currentStatus stop Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::servicePushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:30 volumioandrea volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3","trackType":"mp3"} Sep 01 02:34:30 volumioandrea volumio[858]: verbose: CURRENT POSITION 0 Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState stateService play Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState currentStatus play Sep 01 02:34:30 volumioandrea volumio[858]: info: Received an update from plugin. extracting info from payload Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::servicePushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:30 volumioandrea volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3","trackType":"mp3"} Sep 01 02:34:30 volumioandrea volumio[858]: verbose: CURRENT POSITION 0 Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState stateService play Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState currentStatus play Sep 01 02:34:30 volumioandrea volumio[858]: info: Received an update from plugin. extracting info from payload Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::servicePushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:30 volumioandrea volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3","trackType":"mp3"} Sep 01 02:34:30 volumioandrea volumio[858]: verbose: CURRENT POSITION 0 Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState stateService play Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState currentStatus play Sep 01 02:34:30 volumioandrea volumio[858]: info: Received an update from plugin. extracting info from payload Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 312ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 410ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 408ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 407ms Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand playlistinfo took 264 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand playlistinfo took 251 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand playlistinfo took 248 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: info: sendMpdCommand playlistinfo took 247 milliseconds Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseTrackInfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseTrackInfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseTrackInfo Sep 01 02:34:30 volumioandrea volumio[858]: verbose: ControllerMpd::parseTrackInfo Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::servicePushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:30 volumioandrea volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3","trackType":"mp3"} Sep 01 02:34:30 volumioandrea volumio[858]: verbose: CURRENT POSITION 0 Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState stateService play Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState currentStatus play Sep 01 02:34:30 volumioandrea volumio[858]: info: Received an update from plugin. extracting info from payload Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::servicePushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:30 volumioandrea volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3","trackType":"mp3"} Sep 01 02:34:30 volumioandrea volumio[858]: verbose: CURRENT POSITION 0 Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState stateService play Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState currentStatus play Sep 01 02:34:30 volumioandrea volumio[858]: info: Received an update from plugin. extracting info from payload Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::servicePushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:30 volumioandrea volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3","trackType":"mp3"} Sep 01 02:34:30 volumioandrea volumio[858]: verbose: CURRENT POSITION 0 Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState stateService play Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState currentStatus play Sep 01 02:34:30 volumioandrea volumio[858]: info: Received an update from plugin. extracting info from payload Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: ControllerMpd::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::servicePushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CorePlayQueue::getTrack 0 Sep 01 02:34:30 volumioandrea volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"file.mp3","artist":null,"album":null,"uri":"http://192.168.178.30:32469/object/11b50cf64e08709398d9/file.mp3","trackType":"mp3"} Sep 01 02:34:30 volumioandrea volumio[858]: verbose: CURRENT POSITION 0 Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState stateService play Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::syncState currentStatus play Sep 01 02:34:30 volumioandrea volumio[858]: info: Received an update from plugin. extracting info from payload Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreStateMachine::pushState Sep 01 02:34:30 volumioandrea volumio[858]: info: CoreCommandRouter::volumioPushState Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 436ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 401ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 397ms Sep 01 02:34:30 volumioandrea volumio[858]: info: ------------------------------ 391ms Sep 01 02:34:30 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:30 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:30 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - volume level for loudness 60 gain applied 0.00 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - Loudness is ON true Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:31 volumioandrea volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 01 02:34:39 volumioandrea volumio[858]: info: No valid Plugin REST Endpoint Sep 01 02:34:53 volumioandrea volumio[858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 02:34:53 volumioandrea volumio[858]: Error: connect ETIMEDOUT 146.75.62.79:80 Sep 01 02:34:53 volumioandrea volumio[858]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 01 02:34:53 volumioandrea volumio[858]: errno: -110, Sep 01 02:34:53 volumioandrea volumio[858]: code: 'ETIMEDOUT', Sep 01 02:34:53 volumioandrea volumio[858]: syscall: 'connect', Sep 01 02:34:53 volumioandrea volumio[858]: address: '146.75.62.79', Sep 01 02:34:53 volumioandrea volumio[858]: port: 80 Sep 01 02:34:53 volumioandrea volumio[858]: } Sep 01 02:34:53 volumioandrea volumio[858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 02:34:53 volumioandrea sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 02:33 Sep 01 02:34:53 volumioandrea sudo[1602]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"