Mar 26 11:33:02 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:02 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:02 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:02 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:02 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:07 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:07 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:07 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:07 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:07 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:11 volumiopoo volumio[1228]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/pi_screen_setup/volumio/bookworm/armhf Mar 26 11:33:11 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:11.505+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:12 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:12 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:12 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:12 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:12 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:14 volumiopoo volumio[1228]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/pi_screen_setup/volumio/bookworm/armhf Mar 26 11:33:14 volumiopoo volumio[1228]: info: Folder /tmp/plugins removed Mar 26 11:33:14 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:14.155+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:14 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:14.252+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:14 volumiopoo volumio[1228]: info: Check plugin dependencies Mar 26 11:33:14 volumiopoo volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 11:33:14 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:14.268+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:14 volumiopoo volumio[1228]: info: Checking if plugin already exists Mar 26 11:33:14 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:14.270+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:14 volumiopoo volumio[1228]: info: Rename folder Mar 26 11:33:14 volumiopoo volumio[1228]: info: Folder /tmp/downloaded_plugin.zip removed Mar 26 11:33:14 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:14.280+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:14 volumiopoo volumio[1228]: info: Move to category Mar 26 11:33:15 volumiopoo volumio[1228]: info: Checking if install.sh is present Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.062+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Executing install.sh Mar 26 11:33:15 volumiopoo sudo[2962]: volumio : unable to resolve host volumiopoo: System error Mar 26 11:33:15 volumiopoo sudo[2962]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/pi_screen_setup/install.sh Mar 26 11:33:15 volumiopoo sudo[2962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 11:33:15 volumiopoo volumio[1228]: info: Installing pi_screen_setup... Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.088+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Detected: Raspberry Pi 5 Model B Rev 1.0 Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.091+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Creating sudoers entry for pi_screen_setup... Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.094+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: /etc/sudoers.d/volumio-user-pi_screen_setup: parsed OK Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.108+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Sudoers configuration complete. Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.112+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Creating data directories... Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.114+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Boot partition verified. Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.130+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Checking factory backups... Mar 26 11:33:15 volumiopoo volumio[1228]: info: Creating factory backup of config.txt... Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.133+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.133+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Creating factory backup of cmdline.txt... Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.139+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Creating factory backup of volumioconfig.txt... Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.142+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Creating factory backup of userconfig.txt... Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.146+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Factory backups created. Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.150+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Checking for existing display configuration... Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.152+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Found display settings in volumioconfig.txt Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.158+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Mar 26 11:33:15 volumiopoo sudo[2962]: pam_unix(sudo:session): session closed for user root Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.162+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: NOTE: Existing display configuration detected. Mar 26 11:33:15 volumiopoo volumio[1228]: info: The plugin will offer to migrate these settings on first use. Mar 26 11:33:15 volumiopoo volumio[1228]: info: Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.165+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.165+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.165+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Install script completed Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.172+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Adding reference to registry Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.174+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Done installing plugin. Mar 26 11:33:15 volumiopoo volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 11:33:15 volumiopoo volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 11:33:15 volumiopoo volumio[1228]: info: Folder /tmp/plugins removed Mar 26 11:33:15 volumiopoo volumio[1228]: info: Folder /tmp/downloaded_plugin.zip removed Mar 26 11:33:15 volumiopoo volumio[1228]: info: Folder /data/temp removed Mar 26 11:33:15 volumiopoo volumio[1228]: info: Created placeholder /boot/videoconfig.txt Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.187+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:15 volumiopoo volumio[1228]: info: Installation complete. Mar 26 11:33:15 volumiopoo volumio[1228]: Plugin install end detected on script Mar 26 11:33:15 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:33:15.191+07:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 11:33:16 volumiopoo volumio[1228]: info: Enabling plugin pi_screen_setup Mar 26 11:33:16 volumiopoo volumio[1228]: info: Loading plugin "pi_screen_setup"... Mar 26 11:33:16 volumiopoo volumio[1228]: info: PLUGIN START: pi_screen_setup Mar 26 11:33:16 volumiopoo volumio[1228]: info: pi_screen_setup: Starting plugin Mar 26 11:33:16 volumiopoo volumio[1228]: info: pi_screen_setup: Loaded bundled presets v1.5.5 Mar 26 11:33:16 volumiopoo volumio[1228]: info: pi_screen_setup: Active presets source: bundled, 224 presets loaded Mar 26 11:33:16 volumiopoo volumio[1228]: info: pi_screen_setup: Detected model: Raspberry Pi 5 Model B Rev 1.0 Mar 26 11:33:16 volumiopoo volumio[1228]: info: pi_screen_setup: Detected SoC: bcm2712 Mar 26 11:33:16 volumiopoo volumio[1228]: info: pi_screen_setup: Detected RAM: 1985 MB Mar 26 11:33:16 volumiopoo volumio[1228]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 5","model_raw":"Raspberry Pi 5 Model B Rev 1.0","soc":"bcm2712","ram_mb":1985,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi5","is_pi":true} Mar 26 11:33:16 volumiopoo volumio[1228]: info: pi_screen_setup: Management server started on port 4567 Mar 26 11:33:16 volumiopoo volumio[1228]: info: Done. Mar 26 11:33:17 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:17 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:17 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:17 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:17 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:17 volumiopoo volumio[1228]: info: Enabling plugin pi_screen_setup Mar 26 11:33:17 volumiopoo volumio[1228]: info: Loading plugin "pi_screen_setup"... Mar 26 11:33:17 volumiopoo volumio[1228]: info: PLUGIN START: pi_screen_setup Mar 26 11:33:17 volumiopoo volumio[1228]: info: pi_screen_setup: Starting plugin Mar 26 11:33:17 volumiopoo volumio[1228]: info: pi_screen_setup: Loaded bundled presets v1.5.5 Mar 26 11:33:17 volumiopoo volumio[1228]: info: pi_screen_setup: Active presets source: bundled, 224 presets loaded Mar 26 11:33:17 volumiopoo volumio[1228]: info: pi_screen_setup: Detected model: Raspberry Pi 5 Model B Rev 1.0 Mar 26 11:33:17 volumiopoo volumio[1228]: info: pi_screen_setup: Detected SoC: bcm2712 Mar 26 11:33:17 volumiopoo volumio[1228]: info: pi_screen_setup: Detected RAM: 1985 MB Mar 26 11:33:17 volumiopoo volumio[1228]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 5","model_raw":"Raspberry Pi 5 Model B Rev 1.0","soc":"bcm2712","ram_mb":1985,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi5","is_pi":true} Mar 26 11:33:17 volumiopoo volumio[1228]: error: pi_screen_setup: Port 4567 already in use Mar 26 11:33:17 volumiopoo volumio[1228]: info: Done. Mar 26 11:33:22 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:22 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:22 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:22 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:22 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:26 volumiopoo volumio[1228]: info: Enabling plugin pi_screen_setup Mar 26 11:33:26 volumiopoo volumio[1228]: info: Loading plugin "pi_screen_setup"... Mar 26 11:33:26 volumiopoo volumio[1228]: info: PLUGIN START: pi_screen_setup Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Starting plugin Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Loaded bundled presets v1.5.5 Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Active presets source: bundled, 224 presets loaded Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Detected model: Raspberry Pi 5 Model B Rev 1.0 Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Detected SoC: bcm2712 Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Detected RAM: 1985 MB Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 5","model_raw":"Raspberry Pi 5 Model B Rev 1.0","soc":"bcm2712","ram_mb":1985,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi5","is_pi":true} Mar 26 11:33:26 volumiopoo volumio[1228]: error: pi_screen_setup: Port 4567 already in use Mar 26 11:33:26 volumiopoo volumio[1228]: info: Done. Mar 26 11:33:26 volumiopoo volumio[1228]: info: Enabling plugin pi_screen_setup Mar 26 11:33:26 volumiopoo volumio[1228]: info: Loading plugin "pi_screen_setup"... Mar 26 11:33:26 volumiopoo volumio[1228]: info: PLUGIN START: pi_screen_setup Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Starting plugin Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Loaded bundled presets v1.5.5 Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Active presets source: bundled, 224 presets loaded Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Detected model: Raspberry Pi 5 Model B Rev 1.0 Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Detected SoC: bcm2712 Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Detected RAM: 1985 MB Mar 26 11:33:26 volumiopoo volumio[1228]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 5","model_raw":"Raspberry Pi 5 Model B Rev 1.0","soc":"bcm2712","ram_mb":1985,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi5","is_pi":true} Mar 26 11:33:26 volumiopoo volumio[1228]: error: pi_screen_setup: Port 4567 already in use Mar 26 11:33:26 volumiopoo volumio[1228]: info: Done. Mar 26 11:33:27 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:27 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:27 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:27 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:27 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:32 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:32 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:32 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:32 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:32 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:35 volumiopoo volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 11:33:35 volumiopoo volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 11:33:37 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:37 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:37 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:37 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:37 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:39 volumiopoo volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 11:33:39 volumiopoo volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 11:33:42 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:42 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:42 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:42 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:42 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:47 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:47 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:47 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:47 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:47 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:53 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:53 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:53 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:53 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:53 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:33:58 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:58 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:58 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:33:58 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:33:58 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:34:02 volumiopoo volumio[1228]: info: Clearing queue after UPNP request Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::ClearQueue Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::stop Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::stPlaybackTimer Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::updateTrackBlock Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrackBlock Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::serviceStop Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::serviceStop Mar 26 11:34:02 volumiopoo volumio[1228]: error: WARNING: No stop method for service 80s80s Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::clearPlayQueue Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::saveQueue Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushQueue Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.480+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.480+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id=http://streams.80s80s.de/hamburg/mp3-192/volumio title="Tina Turner - Private Dancer" Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.480+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.481+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio[1228]: info: Mar 26 11:34:02 volumiopoo volumio[1228]: ---------------------------- MPD announces system playlist update Mar 26 11:34:02 volumiopoo volumio[1228]: info: Ignoring MPD Status Update Mar 26 11:34:02 volumiopoo volumio[1228]: info: Mar 26 11:34:02 volumiopoo volumio[1228]: ---------------------------- MPD announces state update: player Mar 26 11:34:02 volumiopoo volumio[1228]: info: ControllerMpd::getState Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Mar 26 11:34:02 volumiopoo volumio[1228]: info: Mar 26 11:34:02 volumiopoo volumio[1228]: ---------------------------- MPD announces system playlist update Mar 26 11:34:02 volumiopoo volumio[1228]: info: Ignoring MPD Status Update Mar 26 11:34:02 volumiopoo volumio[1228]: info: Mar 26 11:34:02 volumiopoo volumio[1228]: ---------------------------- MPD announces state update: player Mar 26 11:34:02 volumiopoo volumio[1228]: info: ControllerMpd::getState Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Mar 26 11:34:02 volumiopoo volumio[1228]: info: Mar 26 11:34:02 volumiopoo volumio[1228]: ---------------------------- MPD announces system playlist update Mar 26 11:34:02 volumiopoo volumio[1228]: info: Ignoring MPD Status Update Mar 26 11:34:02 volumiopoo volumio[1228]: info: Mar 26 11:34:02 volumiopoo volumio[1228]: ---------------------------- MPD announces state update: player Mar 26 11:34:02 volumiopoo volumio[1228]: error: updateQueue error: null Mar 26 11:34:02 volumiopoo volumio[1228]: error: updateQueue error: null Mar 26 11:34:02 volumiopoo volumio[1228]: info: ControllerMpd::getState Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Mar 26 11:34:02 volumiopoo volumio[1228]: info: ------------------------------ 1ms Mar 26 11:34:02 volumiopoo volumio[1228]: info: sendMpdCommand status took 2 milliseconds Mar 26 11:34:02 volumiopoo volumio[1228]: info: ------------------------------ 1ms Mar 26 11:34:02 volumiopoo volumio[1228]: info: sendMpdCommand status took 1 milliseconds Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: ControllerMpd::parseState Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: ControllerMpd::parseState Mar 26 11:34:02 volumiopoo volumio[1228]: info: ControllerMpd::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::servicePushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: CURRENT POSITION 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::syncState stateService stop Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: No code Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: ControllerMpd::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::servicePushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: CURRENT POSITION 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::syncState stateService stop Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: No code Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.509+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.509+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.510+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.510+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.511+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.511+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.511+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.512+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.512+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.512+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.513+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.513+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio[1228]: info: ------------------------------ 22ms Mar 26 11:34:02 volumiopoo volumio[1228]: info: ------------------------------ 21ms Mar 26 11:34:02 volumiopoo volumio[1228]: error: updateQueue error: null Mar 26 11:34:02 volumiopoo volumio[1228]: info: ------------------------------ 22ms Mar 26 11:34:02 volumiopoo volumio[1228]: info: sendMpdCommand status took 22 milliseconds Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: ControllerMpd::parseState Mar 26 11:34:02 volumiopoo volumio[1228]: info: ControllerMpd::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::servicePushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 26 11:34:02 volumiopoo volumio[1228]: verbose: CURRENT POSITION 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::syncState stateService stop Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: No code Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreStateMachine::pushState Mar 26 11:34:02 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:02 volumiopoo volumio[1228]: info: CoreCommandRouter::volumioPushState Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.526+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.527+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.527+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.527+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.528+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 26 11:34:02 volumiopoo volumio5-onboarding[1424]: time=2026-03-26T11:34:02.528+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.37:39352,00:00:00:00:00:00%01 @ 0x18ab1a0" id= title= Mar 26 11:34:02 volumiopoo volumio[1228]: info: ------------------------------ 31ms Mar 26 11:34:03 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:34:03 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:34:03 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:34:03 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:34:03 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:34:08 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:34:08 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:34:08 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:34:08 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:34:08 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:34:13 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:34:13 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:34:13 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:34:13 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:34:13 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:34:18 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:34:18 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:34:18 volumiopoo volumio[1228]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 26 11:34:18 volumiopoo volumio[1228]: info: Discovery: Restarting Browsing Mar 26 11:34:18 volumiopoo volumio[1228]: info: Discovery: Restart already pending, ignoring duplicate call Mar 26 11:34:21 volumiopoo volumio[1228]: info: [1774499661126] [80s80s] Pushing the next song state Tina Turner - Private Dancer and getting next track. Mar 26 11:34:21 volumiopoo volumio[1228]: info: CorePlayQueue::getTrack 0 Mar 26 11:34:21 volumiopoo volumio[1228]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 11:34:21 volumiopoo volumio[1228]: TypeError: Cannot set properties of undefined (setting 'name') Mar 26 11:34:21 volumiopoo volumio[1228]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Mar 26 11:34:21 volumiopoo volumio[1228]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Mar 26 11:34:21 volumiopoo volumio[1228]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Mar 26 11:34:21 volumiopoo volumio[1228]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Mar 26 11:34:21 volumiopoo volumio[1228]: at process.processImmediate (node:internal/timers:478:21) Mar 26 11:34:21 volumiopoo volumio[1228]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 11:34:21 volumiopoo sudo[3252]: volumio : unable to resolve host volumiopoo: System error Mar 26 11:34:21 volumiopoo sudo[3252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 11:33' Mar 26 11:34:21 volumiopoo sudo[3252]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"