Aug 30 00:40:00 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:00.100+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-592.021µs timeout=10s endpoint=https://database.volumio.cloud duration=1.349452863s Aug 30 00:40:04 volumio volumio[1229]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 00:40:04 volumio volumio[1229]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 00:40:04 volumio volumio[1229]: info: Retrieving Cloud Streaming UI Aug 30 00:40:05 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:05 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:05 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:05 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:05 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:05 volumio volumio[1229]: info: Getting Tidal Cloud Configuration Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 00:40:05 volumio volumio[1229]: info: Getting Qobuz Cloud Configuration Aug 30 00:40:05 volumio volumio[1229]: info: Asking plugin for UI Config Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 00:40:05 volumio volumio[1229]: info: Getting Spotify Cloud Configuration Aug 30 00:40:05 volumio volumio[1229]: info: Asking plugin for UI Config Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 00:40:05 volumio volumio[1229]: info: Saving Spotify Acccount Aug 30 00:40:05 volumio volumio[1229]: info: Got Tidal Cloud Configuration Aug 30 00:40:05 volumio volumio[1229]: info: Got it Aug 30 00:40:05 volumio volumio[1229]: info: Got it Aug 30 00:40:05 volumio volumio[1229]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 00:40:05 volumio volumio[1229]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 00:40:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:06 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 30 00:40:08 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand rescan Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:08 volumio volumio[1229]: info: Aug 30 00:40:08 volumio volumio[1229]: ---------------------------- MPD announces state update: update Aug 30 00:40:08 volumio volumio[1229]: info: sendMpdCommand rescan took 44 milliseconds Aug 30 00:40:08 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:08 volumio volumio[1229]: info: Aug 30 00:40:08 volumio volumio[1229]: ---------------------------- MPD announces state update: update Aug 30 00:40:08 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:08 volumio volumio[1229]: info: Aug 30 00:40:08 volumio volumio[1229]: ---------------------------- MPD announces state update: update Aug 30 00:40:08 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:08 volumio volumio[1229]: info: sendMpdCommand status took 4 milliseconds Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:08 volumio volumio[1229]: info: Command Router : Notfying DB Updatetrue Aug 30 00:40:08 volumio volumio[1229]: info: sendMpdCommand status took 254 milliseconds Aug 30 00:40:08 volumio volumio[1229]: info: sendMpdCommand status took 254 milliseconds Aug 30 00:40:08 volumio volumio[1229]: info: sendMpdCommand status took 253 milliseconds Aug 30 00:40:08 volumio volumio[1229]: info: sendMpdCommand status took 253 milliseconds Aug 30 00:40:08 volumio volumio[1229]: info: sendMpdCommand status took 253 milliseconds Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:08 volumio volumio[1229]: info: Command Router : Notfying DB Updatetrue Aug 30 00:40:08 volumio volumio[1229]: info: CoreCommandRouter::Close All Modals sent Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:08 volumio volumio[1229]: info: Command Router : Notfying DB Updatetrue Aug 30 00:40:08 volumio volumio[1229]: info: CoreCommandRouter::Close All Modals sent Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:08 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:09 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 363 milliseconds Aug 30 00:40:09 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 234 milliseconds Aug 30 00:40:09 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 178 milliseconds Aug 30 00:40:09 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:09 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:09 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:09 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:09 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:09 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105725,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:09 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:09 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:09 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:09 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:09 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:09 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:09 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105725,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:09 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:09 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:09 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:09 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:09 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:09 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:09 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105725,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:09 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:09 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:09 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:09 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:09 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.453+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=105725 volume=38 Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.453+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.478+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=105725 volume=38 Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.478+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.479+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=105725 volume=38 Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.479+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.483+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=105725 volume=38 Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.483+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.484+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=105725 volume=38 Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.484+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.485+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=105725 volume=38 Aug 30 00:40:09 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:09.485+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:09 volumio volumio[1229]: info: ------------------------------ 1329ms Aug 30 00:40:09 volumio volumio[1229]: info: ------------------------------ 1328ms Aug 30 00:40:09 volumio volumio[1229]: info: ------------------------------ 1328ms Aug 30 00:40:09 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:10 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:10 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:10 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:10 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:10 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:11 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 30 00:40:11 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand update Aug 30 00:40:11 volumio volumio[1229]: info: sendMpdCommand update took 22 milliseconds Aug 30 00:40:13 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:15 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:15 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:15 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:15 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:15 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:17 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:20 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:20 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:20 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:20 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:20 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:21 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:25 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:26 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:26 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:26 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:26 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:26 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:29 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:31 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:31 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:31 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:31 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:31 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:33 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:36 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:36 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:36 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:36 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:36 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:37 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:41 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:41 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:41 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:41 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:41 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:41 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:45 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:46 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:46 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:46 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:46 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:46 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:51 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:51 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:51 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:51 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:51 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:52 volumio volumio[1229]: info: MPD Database updated - AlbumList cache refreshed Aug 30 00:40:52 volumio volumio[1229]: info: Aug 30 00:40:52 volumio volumio[1229]: ---------------------------- MPD announces state update: database Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:52 volumio volumio[1229]: info: Aug 30 00:40:52 volumio volumio[1229]: ---------------------------- MPD announces state update: update Aug 30 00:40:52 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:52 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:52 volumio volumio[1229]: info: MPD Database updated - AlbumList cache refreshed Aug 30 00:40:52 volumio volumio[1229]: info: Aug 30 00:40:52 volumio volumio[1229]: ---------------------------- MPD announces state update: database Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:52 volumio volumio[1229]: info: Aug 30 00:40:52 volumio volumio[1229]: ---------------------------- MPD announces state update: update Aug 30 00:40:52 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:52 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:52 volumio volumio[1229]: info: MPD Database updated - AlbumList cache refreshed Aug 30 00:40:52 volumio volumio[1229]: info: Aug 30 00:40:52 volumio volumio[1229]: ---------------------------- MPD announces state update: database Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:52 volumio volumio[1229]: info: Aug 30 00:40:52 volumio volumio[1229]: ---------------------------- MPD announces state update: update Aug 30 00:40:52 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:52 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:52 volumio volumio[1229]: info: sendMpdCommand status took 581 milliseconds Aug 30 00:40:52 volumio volumio[1229]: info: sendMpdCommand status took 582 milliseconds Aug 30 00:40:52 volumio volumio[1229]: info: sendMpdCommand status took 581 milliseconds Aug 30 00:40:52 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:53 volumio volumio[1229]: info: Command Router : Notfying DB Updatetrue Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::Close All Modals sent Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand status took 751 milliseconds Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand status took 751 milliseconds Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand status took 752 milliseconds Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:53 volumio volumio[1229]: info: Command Router : Notfying DB Updatetrue Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::Close All Modals sent Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand status took 866 milliseconds Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand status took 866 milliseconds Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand status took 866 milliseconds Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:53 volumio volumio[1229]: info: Command Router : Notfying DB Updatetrue Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::Close All Modals sent Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 219 milliseconds Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 219 milliseconds Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 116 milliseconds Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 115 milliseconds Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 30 00:40:53 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:53 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:53 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149800,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:53 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:53 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149800,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:53 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:53 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149800,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:53 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:53 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149800,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:53 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:53 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149843,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:53 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:53 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":149843,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:53 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:53 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:53 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.352+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149800 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.353+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.353+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149800 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.353+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.355+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149800 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.356+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.357+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149800 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.357+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.363+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149800 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.363+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149800 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.363+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.363+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149800 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.363+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.363+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.365+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149800 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.367+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149843 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.367+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.367+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149843 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.367+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.369+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149843 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.369+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=149843 volume=38 Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.370+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.370+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:53.390+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:53 volumio volumio[1229]: info: ------------------------------ 1019ms Aug 30 00:40:53 volumio volumio[1229]: info: ------------------------------ 1019ms Aug 30 00:40:53 volumio volumio[1229]: info: ------------------------------ 1019ms Aug 30 00:40:53 volumio volumio[1229]: info: ------------------------------ 1018ms Aug 30 00:40:53 volumio volumio[1229]: info: ------------------------------ 1018ms Aug 30 00:40:53 volumio volumio[1229]: info: ------------------------------ 1018ms Aug 30 00:40:53 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:40:54 volumio volumio[1229]: info: MPD Database updated - AlbumList cache refreshed Aug 30 00:40:54 volumio volumio[1229]: info: Aug 30 00:40:54 volumio volumio[1229]: ---------------------------- MPD announces state update: database Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:54 volumio volumio[1229]: info: Aug 30 00:40:54 volumio volumio[1229]: ---------------------------- MPD announces state update: update Aug 30 00:40:54 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:54 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:54 volumio volumio[1229]: info: MPD Database updated - AlbumList cache refreshed Aug 30 00:40:54 volumio volumio[1229]: info: Aug 30 00:40:54 volumio volumio[1229]: ---------------------------- MPD announces state update: database Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:54 volumio volumio[1229]: info: Aug 30 00:40:54 volumio volumio[1229]: ---------------------------- MPD announces state update: update Aug 30 00:40:54 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:54 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:54 volumio volumio[1229]: info: MPD Database updated - AlbumList cache refreshed Aug 30 00:40:54 volumio volumio[1229]: info: Aug 30 00:40:54 volumio volumio[1229]: ---------------------------- MPD announces state update: database Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:54 volumio volumio[1229]: info: Aug 30 00:40:54 volumio volumio[1229]: ---------------------------- MPD announces state update: update Aug 30 00:40:54 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:54 volumio volumio[1229]: info: ControllerMpd::getState Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Aug 30 00:40:54 volumio volumio[1229]: info: sendMpdCommand status took 160 milliseconds Aug 30 00:40:54 volumio volumio[1229]: info: sendMpdCommand status took 160 milliseconds Aug 30 00:40:54 volumio volumio[1229]: info: sendMpdCommand status took 160 milliseconds Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:54 volumio volumio[1229]: info: Command Router : Notfying DB Updatefalse Aug 30 00:40:54 volumio volumio[1229]: info: CoreCommandRouter::Close All Modals sent Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:54 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand status took 344 milliseconds Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand status took 345 milliseconds Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand status took 345 milliseconds Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:55 volumio volumio[1229]: info: Command Router : Notfying DB Updatefalse Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::Close All Modals sent Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand status took 440 milliseconds Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand status took 439 milliseconds Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand status took 439 milliseconds Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 178 milliseconds Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 178 milliseconds Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:55 volumio volumio[1229]: info: Command Router : Notfying DB Updatefalse Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::Close All Modals sent Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseState Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:55 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152018,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:55 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:55 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152018,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:55 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:55 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.166+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152018 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.167+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.167+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152018 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.168+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.169+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152018 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.169+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.169+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152018 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.169+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio[1229]: info: ------------------------------ 487ms Aug 30 00:40:55 volumio volumio[1229]: info: ------------------------------ 488ms Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 123 milliseconds Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 123 milliseconds Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 30 00:40:55 volumio volumio[1229]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:55 volumio volumio[1229]: verbose: ControllerMpd::parseTrackInfo Aug 30 00:40:55 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152018,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:55 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:55 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152018,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:55 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:55 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152061,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:55 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:55 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: ControllerMpd::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::servicePushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152061,"duration":280,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')","artist":"I MUSICI","album":"MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3","uri":"USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF","trackType":"DSF"} Aug 30 00:40:55 volumio volumio[1229]: verbose: CURRENT POSITION 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState stateService play Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::syncState currentStatus play Aug 30 00:40:55 volumio volumio[1229]: info: Received an update from plugin. extracting info from payload Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio[1229]: info: CoreStateMachine::pushState Aug 30 00:40:55 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:40:55 volumio volumio[1229]: info: CoreCommandRouter::volumioPushState Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.209+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152018 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.209+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.210+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152018 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.210+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.211+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152018 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.211+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.212+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152018 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.212+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.213+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152061 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.213+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.214+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152061 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.214+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.215+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152061 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.216+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" state=STATUS_PLAYING positionMs=152061 volume=38 Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.217+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio5-onboarding[1549]: time=2026-08-30T00:40:55.217+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.187:33206 @ 0x2d8e060" id="mnt/USB/Elements/I MUSICI - MENDELSSOHN, OCTET - WOLF, ITALIENISCHE SERENADE - ROSSINI, SONATA A QUATTRO NO.3 (1966)/03_MENDELSSOHN,_OCTET_IN_E_FLAT_OP_20_III_SCHERZO_'ALLEGRO_LEGGIERISSIMO' (1).DSF" title="3 - MENDELSSOHN, OCTET IN E-FLAT OP.20 (III. SCHERZO 'ALLEGRO LEGGIERISSIMO')" Aug 30 00:40:55 volumio volumio[1229]: info: ------------------------------ 546ms Aug 30 00:40:55 volumio volumio[1229]: info: ------------------------------ 547ms Aug 30 00:40:55 volumio volumio[1229]: info: ------------------------------ 546ms Aug 30 00:40:55 volumio volumio[1229]: info: ------------------------------ 547ms Aug 30 00:40:56 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:56 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:56 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:40:56 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:40:56 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:40:57 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:41:01 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:41:01 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:41:01 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:41:01 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:41:01 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:41:01 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:41:05 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:41:06 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:41:06 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:41:06 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:41:06 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:41:06 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.361+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.379+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=17.598613ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.390+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=25.341559ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.405+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=40.41822ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.405+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=http://pushupdates.volumio.org duration=39.309889ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.433+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://google.com duration=66.62206ms Aug 30 00:41:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 00:41:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 00:41:07 volumio volumio[1229]: info: Discovery: Getting this device information Aug 30 00:41:07 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Aug 30 00:41:07 volumio volumio[1229]: info: CorePlayQueue::getTrack 2 Aug 30 00:41:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.480+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://www.googleapis.com duration=117.247625ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.501+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://securetoken.googleapis.com duration=137.988401ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.502+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=138.179063ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.617+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://functions.volumio.cloud duration=253.001956ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.618+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://database.volumio.cloud duration=252.507818ms Aug 30 00:41:07 volumio volumio[1229]: verbose: New Socket.io Connection to 192.168.1.223:3000 from 192.168.1.187 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 30 00:41:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 00:41:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.769+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=http://cddb.volumio.org duration=404.431826ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.939+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=http://plugins.volumio.org duration=573.528511ms Aug 30 00:41:07 volumio volumio5-onboarding[1549]: time=2026-08-30T00:41:07.942+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.187:33206 @ 0x2d8e060" latency=-9.239576ms timeout=10s endpoint=https://functions.volumio.cloud duration=579.467381ms Aug 30 00:41:09 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:41:11 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:41:11 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:41:11 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:41:11 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:41:11 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:41:13 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:41:16 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:41:16 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:41:16 volumio volumio[1229]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Aug 30 00:41:16 volumio volumio[1229]: info: Discovery: Restarting Browsing Aug 30 00:41:16 volumio volumio[1229]: info: Discovery: Restart already pending, ignoring duplicate call Aug 30 00:41:17 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 00:41:20 volumio volumio[1229]: info: Disabling MyMusic plugin upnp Aug 30 00:41:20 volumio sudo[2966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 30 00:41:20 volumio sudo[2966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 00:41:20 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Aug 30 00:41:21 volumio volumio[1229]: info: Enabling MyMusic plugin upnp Aug 30 00:41:21 volumio volumio[1229]: info: Enabling plugin upnp Aug 30 00:41:21 volumio volumio[1229]: info: Loading plugin "upnp"... Aug 30 00:41:21 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Aug 30 00:41:21 volumio volumio[1229]: info: [1788043281260] Starting Upmpd Daemon Aug 30 00:41:21 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 00:41:21 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 30 00:41:21 volumio systemd[1]: upmpdcli.service: Consumed 19.699s CPU time. Aug 30 00:41:21 volumio sudo[2966]: pam_unix(sudo:session): session closed for user root Aug 30 00:41:21 volumio volumio[1229]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 00:41:21 volumio volumio[1229]: Error: listen EADDRINUSE: address already in use :::6599 Aug 30 00:41:21 volumio volumio[1229]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Aug 30 00:41:21 volumio volumio[1229]: at listenInCluster (node:net:1920:12) Aug 30 00:41:21 volumio volumio[1229]: at Server.listen (node:net:2008:7) Aug 30 00:41:21 volumio volumio[1229]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 30 00:41:21 volumio volumio[1229]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 30 00:41:21 volumio volumio[1229]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 30 00:41:21 volumio volumio[1229]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 30 00:41:21 volumio volumio[1229]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Aug 30 00:41:21 volumio volumio[1229]: code: 'EADDRINUSE', Aug 30 00:41:21 volumio volumio[1229]: errno: -98, Aug 30 00:41:21 volumio volumio[1229]: syscall: 'listen', Aug 30 00:41:21 volumio volumio[1229]: address: '::', Aug 30 00:41:21 volumio volumio[1229]: port: 6599 Aug 30 00:41:21 volumio volumio[1229]: } Aug 30 00:41:21 volumio volumio[1229]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 00:41:22 volumio sudo[2985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 00:40' Aug 30 00:41:22 volumio sudo[2985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"