Mar 04 22:10:01 volumio volumio[1284]: info: camilladsp respawn in 200 ms (attempt 2/10) Mar 04 22:10:01 volumio volumio[1284]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 04 22:10:01 volumio volumio[1284]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Mar 04 22:10:01 volumio volumio[1284]: info: FusionDsp - ---- read samplerate from file: 96000 Mar 04 22:10:01 volumio volumio[1284]: info: camilladsp service terminated, instance 1 Mar 04 22:10:01 volumio volumio[1284]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 22:10:01 volumio volumio[1284]: info: FusionDsp - Effects disabled Mar 04 22:10:01 volumio volumio[1284]: info: camilladsp service started and running in background, instance 1 Mar 04 22:10:01 volumio volumio[1284]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 04 22:10:01 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:01 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:01 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:10:01 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:01 volumio volumio[1284]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 04 22:10:01 volumio volumio[1284]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 04 22:10:01 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:01 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:01 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:01 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:01 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:06 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:06 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:06 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:06 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:06 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:11 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:11 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:11 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:11 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:11 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:17 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:17 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:17 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:17 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:17 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:22 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:22 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:22 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:22 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:22 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:27 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:27 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:27 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:27 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:27 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:33 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:33 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:33 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:33 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:33 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:33 volumio vtcs[2345]: [close:85] Entering Mar 04 22:10:33 volumio vtcs[2345]: [feedThread:276] Exiting Mar 04 22:10:33 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:10:33 volumio volumio[1284]: info: Pushing metadata Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:33 volumio vtcs[2345]: [close:85] Entering Mar 04 22:10:33 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:10:33 volumio volumio[1284]: info: Pushing metadata Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:33 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:34 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 Mar 04 22:10:34 volumio kernel: xhci_hcd 0000:01:00.0: @000000040cd06f90 00000000 00000000 0e000000 04028000 Mar 04 22:10:34 volumio volumio[1284]: info: camilladsp respawn in 100 ms (attempt 1/10) Mar 04 22:10:34 volumio vtcs[2345]: [close:85] Entering Mar 04 22:10:34 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:10:34 volumio vtcs[2345]: [open:50] Opening with sample rate: 96000, type: 3 Mar 04 22:10:34 volumio vtcs[2345]: [start:106] Entering Mar 04 22:10:34 volumio vtcs[2345]: [start:113] Reopening PCM device... Mar 04 22:10:34 volumio vtcs[2345]: [start:115] PCM opened successfully. Mar 04 22:10:34 volumio vtcs[2345]: [start:120] Exiting Mar 04 22:10:34 volumio vtcs[2345]: [feedThread:223] Entering Mar 04 22:10:34 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:34 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:34 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:34 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:34 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:34 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:34 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:34 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:34 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:10:34 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:34 volumio volumio[1284]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Mar 04 22:10:34 volumio volumio[1284]: info: FusionDsp - ---- read samplerate from file: 96000 Mar 04 22:10:35 volumio volumio[1284]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 22:10:35 volumio volumio[1284]: info: FusionDsp - Effects disabled Mar 04 22:10:38 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:38 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:38 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:38 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:38 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:40 volumio vtcs[2345]: [close:85] Entering Mar 04 22:10:40 volumio vtcs[2345]: [feedThread:276] Exiting Mar 04 22:10:40 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:10:40 volumio volumio[1284]: info: Pushing metadata Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:40 volumio vtcs[2345]: [close:85] Entering Mar 04 22:10:40 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:10:40 volumio volumio[1284]: info: Pushing metadata Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:40 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:40 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 Mar 04 22:10:40 volumio kernel: xhci_hcd 0000:01:00.0: @000000040cd0a3d0 00000000 00000000 0e000000 04028000 Mar 04 22:10:41 volumio vtcs[2345]: [close:85] Entering Mar 04 22:10:41 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:10:41 volumio vtcs[2345]: [open:50] Opening with sample rate: 96000, type: 3 Mar 04 22:10:41 volumio vtcs[2345]: [start:106] Entering Mar 04 22:10:41 volumio vtcs[2345]: [start:113] Reopening PCM device... Mar 04 22:10:41 volumio vtcs[2345]: [start:115] PCM opened successfully. Mar 04 22:10:41 volumio vtcs[2345]: [start:120] Exiting Mar 04 22:10:41 volumio vtcs[2345]: [feedThread:223] Entering Mar 04 22:10:41 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:41 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:41 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:41 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:41 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:41 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:41 volumio volumio[1284]: info: camilladsp respawn in 200 ms (attempt 2/10) Mar 04 22:10:41 volumio volumio[1284]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Mar 04 22:10:41 volumio volumio[1284]: info: FusionDsp - ---- read samplerate from file: 96000 Mar 04 22:10:42 volumio volumio[1284]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 22:10:42 volumio volumio[1284]: info: FusionDsp - Effects disabled Mar 04 22:10:42 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:10:42 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:10:42 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:10:42 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:10:43 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:43 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:43 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:43 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:43 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:49 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:49 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:49 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:49 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:49 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:54 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:54 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:54 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:54 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:54 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:10:59 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:59 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:59 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:10:59 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:10:59 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:05 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:05 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:05 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:05 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:05 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:10 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:10 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:10 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:10 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:10 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:15 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:15 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:15 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:15 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:15 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:20 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:20 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:20 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:20 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:20 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:26 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:26 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:26 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:26 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:26 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:30 volumio vtcs[2345]: [close:85] Entering Mar 04 22:11:30 volumio vtcs[2345]: [feedThread:276] Exiting Mar 04 22:11:30 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:11:30 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:30 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:30 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:11:30 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:30 volumio volumio[1284]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 04 22:11:31 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 Mar 04 22:11:31 volumio kernel: xhci_hcd 0000:01:00.0: @000000040cd103c0 00000000 00000000 0e000000 04028000 Mar 04 22:11:31 volumio volumio[1284]: info: camilladsp respawn in 100 ms (attempt 1/10) Mar 04 22:11:31 volumio vtcs[2345]: [2026-03-04 22:11:31.248] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Mar 04 22:11:31 volumio volumio[1284]: info: Pushing metadata Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:31 volumio vtcs[2345]: [close:85] Entering Mar 04 22:11:31 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:11:31 volumio volumio[1284]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 04 22:11:31 volumio volumio[1284]: info: Pushing metadata Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:31 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:31 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:31 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:31 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:31 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:31 volumio vtcs[2345]: [close:85] Entering Mar 04 22:11:31 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:11:31 volumio vtcs[2345]: [open:50] Opening with sample rate: 44100, type: 0 Mar 04 22:11:31 volumio vtcs[2345]: [start:106] Entering Mar 04 22:11:31 volumio vtcs[2345]: [start:113] Reopening PCM device... Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:31 volumio vtcs[2345]: [start:115] PCM opened successfully. Mar 04 22:11:31 volumio vtcs[2345]: [start:120] Exiting Mar 04 22:11:31 volumio vtcs[2345]: [feedThread:223] Entering Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::servicePushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreStateMachine::pushState Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 22:11:31 volumio volumio[1284]: info: CoreCommandRouter::volumioPushState Mar 04 22:11:32 volumio volumio[1284]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 04 22:11:32 volumio volumio[1284]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 04 22:11:32 volumio volumio[1284]: info: camilladsp stopping service pid 2736... Mar 04 22:11:32 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 4 ep 1 Mar 04 22:11:32 volumio kernel: xhci_hcd 0000:01:00.0: @000000040cd12810 00000000 00000000 0e000000 04028000 Mar 04 22:11:32 volumio volumio[1284]: grep: /proc/2736/cmdline: binary file matches Mar 04 22:11:32 volumio volumio[1284]: grep: /proc/2736/cmdline: binary file matches Mar 04 22:11:32 volumio volumio[1284]: grep: /proc/2736/cmdline: binary file matches Mar 04 22:11:32 volumio volumio[1284]: info: camilladsp service terminated, instance 1 Mar 04 22:11:32 volumio volumio[1284]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 04 22:11:32 volumio volumio[1284]: info: FusionDsp - Effects disabled Mar 04 22:11:32 volumio volumio[1284]: info: camilladsp service started and running in background, instance 1 Mar 04 22:11:32 volumio volumio[1284]: info: camilladsp respawn in 100 ms (attempt 1/10) Mar 04 22:11:36 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:36 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:36 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:36 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:36 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:41 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:41 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:41 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:41 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:41 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:43 volumio sudo[2789]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 22:11:43 volumio sudo[2789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 22:11:43 volumio sudo[2789]: pam_unix(sudo:session): session closed for user root Mar 04 22:11:43 volumio sudo[2791]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 22:11:43 volumio sudo[2791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 22:11:43 volumio sudo[2791]: pam_unix(sudo:session): session closed for user root Mar 04 22:11:43 volumio volumio[1284]: verbose: New Socket.io Connection to 192.168.1.20 from 192.168.1.24 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Mar 04 22:11:44 volumio volumio[1284]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 22:11:44 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 22:11:44 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Mar 04 22:11:44 volumio volumio[1284]: info: CoreCommandRouter::volumioGetQueue Mar 04 22:11:44 volumio volumio[1284]: info: CoreStateMachine::getQueue Mar 04 22:11:44 volumio volumio[1284]: info: CorePlayQueue::getQueue Mar 04 22:11:44 volumio volumio[1284]: info: Listing playlists Mar 04 22:11:44 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 04 22:11:44 volumio volumio[1284]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 04 22:11:44 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 04 22:11:44 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 22:11:44 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 22:11:44 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 22:11:46 volumio volumio[1284]: info: Received Get System Info Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:11:46 volumio volumio[1284]: info: Discovery: Getting this device information Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 22:11:46 volumio volumio[1284]: info: Received Get System Info Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 22:11:46 volumio volumio[1284]: info: Discovery: Getting this device information Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::volumioGetState Mar 04 22:11:46 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 22:11:47 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:47 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:47 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:47 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:47 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 22:11:47 volumio volumio[1284]: info: Retrieving Cloud Streaming UI Mar 04 22:11:47 volumio volumio[1284]: info: Getting Tidal Cloud Configuration Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 22:11:47 volumio volumio[1284]: info: Getting Qobuz Cloud Configuration Mar 04 22:11:47 volumio volumio[1284]: info: Asking plugin for UI Config Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 22:11:47 volumio volumio[1284]: info: Getting Spotify Cloud Configuration Mar 04 22:11:47 volumio volumio[1284]: info: Asking plugin for UI Config Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 22:11:47 volumio volumio[1284]: info: Saving Spotify Acccount Mar 04 22:11:47 volumio volumio[1284]: info: Got it Mar 04 22:11:47 volumio volumio[1284]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 04 22:11:47 volumio volumio[1284]: info: Got Tidal Cloud Configuration Mar 04 22:11:47 volumio volumio[1284]: info: Got it Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::volumioGetBrowseSources Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::volumioGetBrowseSources Mar 04 22:11:47 volumio volumio[1284]: info: CoreCommandRouter::volumioGetBrowseSources Mar 04 22:11:48 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 04 22:11:48 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 04 22:11:52 volumio volumio[1284]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 04 22:11:52 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:52 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:52 volumio volumio[1284]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 04 22:11:52 volumio volumio[1284]: info: Discovery: Restarting Browsing Mar 04 22:11:52 volumio volumio[1284]: info: Discovery: Restart already pending, ignoring duplicate call Mar 04 22:11:52 volumio volumio[1284]: info: Enabling MyMusic plugin bluetooth Mar 04 22:11:52 volumio volumio[1284]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Mar 04 22:11:52 volumio volumio[1284]: info: Enabling plugin bluetooth Mar 04 22:11:52 volumio volumio[1284]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 04 22:11:52 volumio volumio[1284]: ------------------------------------ BT MESSAGE: [FUNC] onStart Mar 04 22:11:52 volumio volumio[1284]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Mar 04 22:11:52 volumio volumio[1284]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Mar 04 22:11:52 volumio volumio[1284]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Mar 04 22:11:52 volumio volumio[1284]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Mar 04 22:11:52 volumio bluetoothd[808]: Path / reserved for Adv Monitor app :1.131 Mar 04 22:11:52 volumio bluetoothd[808]: Adv Monitor app :1.131 disconnected from D-Bus Mar 04 22:11:52 volumio volumio[1284]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Mar 04 22:11:52 volumio sudo[2823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 04 22:11:52 volumio sudo[2823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 22:11:52 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Mar 04 22:11:52 volumio sudo[2823]: pam_unix(sudo:session): session closed for user root Mar 04 22:11:52 volumio volumio[1284]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Mar 04 22:11:52 volumio volumio[1284]: info: CoreCommandRouter::volumioStop Mar 04 22:11:52 volumio volumio[1284]: info: CoreStateMachine::stop Mar 04 22:11:52 volumio volumio[1284]: info: CoreStateMachine::serviceStop Mar 04 22:11:52 volumio volumio[1284]: info: CoreCommandRouter::serviceStop Mar 04 22:11:52 volumio volumio[1284]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 22:11:52 volumio volumio[1284]: TypeError: Cannot read properties of undefined (reading 'then') Mar 04 22:11:52 volumio volumio[1284]: at /myvolumio/plugins/audio_interface/bluetooth/bluetooth_real:1:12468 Mar 04 22:11:52 volumio volumio[1284]: at ChildProcess.exithandler (node:child_process:413:7) Mar 04 22:11:52 volumio volumio[1284]: at ChildProcess.emit (node:events:514:28) Mar 04 22:11:52 volumio volumio[1284]: at maybeClose (node:internal/child_process:1105:16) Mar 04 22:11:52 volumio volumio[1284]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Mar 04 22:11:52 volumio volumio[1284]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 22:11:52 volumio volumiobt[2826]: INFO [BTSTART] Ensuring Bluetooth directory exists... Mar 04 22:11:52 volumio vtcs[2345]: [close:85] Entering Mar 04 22:11:52 volumio sudo[2828]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Mar 04 22:11:52 volumio sudo[2828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 22:11:52 volumio sudo[2828]: pam_unix(sudo:session): session closed for user root Mar 04 22:11:52 volumio sudo[2833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Mar 04 22:11:52 volumio sudo[2833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 22:11:52 volumio sudo[2833]: pam_unix(sudo:session): session closed for user root Mar 04 22:11:52 volumio volumiobt[2843]: INFO [BTSTART] Powering on Bluetooth if needed... Mar 04 22:11:52 volumio vtcs[2345]: [feedThread:276] Exiting Mar 04 22:11:52 volumio vtcs[2345]: [close:100] Exiting Mar 04 22:11:52 volumio bluetoothd[808]: Adv Monitor app :1.132 disconnected from D-Bus Mar 04 22:11:52 volumio volumiobt[2847]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Mar 04 22:11:53 volumio sudo[2852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 22:10' Mar 04 22:11:53 volumio sudo[2852]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"