Oct 17 01:05:03 screenplay systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 17 01:05:03 screenplay systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 17 01:05:03 screenplay systemd[1]: setdatetime-helper.service: Consumed 2.086s CPU time. Oct 17 01:05:03 screenplay systemd[1]: Reached target multi-user.target - Multi-User System. Oct 17 01:05:03 screenplay systemd[1]: Reached target graphical.target - Graphical Interface. Oct 17 01:05:03 screenplay volumio[1228]: info: ------------------------------------------- Oct 17 01:05:03 screenplay volumio[1228]: info: ----- Volumio3 ---- Oct 17 01:05:03 screenplay volumio[1228]: info: ------------------------------------------- Oct 17 01:05:03 screenplay volumio[1228]: info: ----- System startup ---- Oct 17 01:05:03 screenplay volumio[1228]: info: ------------------------------------------- Oct 17 01:05:03 screenplay systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 17 01:05:03 screenplay systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 17 01:05:03 screenplay systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 17 01:05:03 screenplay systemd[1]: Startup finished in 21.943s (kernel) + 43.182s (userspace) = 1min 5.125s. Oct 17 01:05:05 screenplay volumio[1228]: info: MYVOLUMIO Environment detected Oct 17 01:05:05 screenplay volumio[1228]: info: Plugin folders cleanup Oct 17 01:05:05 screenplay volumio[1228]: info: Scanning into folder /volumio/app/plugins/ Oct 17 01:05:05 screenplay volumio[1228]: info: Scanning category audio_interface Oct 17 01:05:05 screenplay volumio[1228]: info: Scanning category miscellanea Oct 17 01:05:06 screenplay volumio[1228]: info: Scanning category music_service Oct 17 01:05:06 screenplay volumio[1228]: info: Scanning category plugins.json Oct 17 01:05:06 screenplay volumio[1228]: info: Scanning category system_controller Oct 17 01:05:06 screenplay volumio[1228]: info: Scanning category user_interface Oct 17 01:05:06 screenplay volumio[1228]: info: Scanning into folder /data/plugins/ Oct 17 01:05:06 screenplay volumio[1228]: info: Scanning category music_service Oct 17 01:05:06 screenplay volumio[1228]: info: Scanning category user_interface Oct 17 01:05:06 screenplay volumio[1228]: info: Plugin folders cleanup completed Oct 17 01:05:06 screenplay volumio[1228]: info: ------------------------------------------- Oct 17 01:05:06 screenplay volumio[1228]: info: ----- Core plugins startup ---- Oct 17 01:05:06 screenplay volumio[1228]: info: ------------------------------------------- Oct 17 01:05:06 screenplay volumio[1228]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 01:05:06 screenplay volumio[1228]: info: Adding plugin upnp to MyMusic Plugins Oct 17 01:05:06 screenplay volumio[1228]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 01:05:06 screenplay volumio[1228]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 01:05:06 screenplay volumio[1228]: info: Loading plugins from folder /data/plugins/ Oct 17 01:05:06 screenplay volumio[1228]: info: Loading plugin "system"... Oct 17 01:05:06 screenplay volumio[1228]: info: Loading plugin "appearance"... Oct 17 01:05:07 screenplay systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 17 01:05:09 screenplay volumio[1228]: info: Loading plugin "network"... Oct 17 01:05:09 screenplay volumio[1228]: info: Refreshing Cached IP Addresses Oct 17 01:05:09 screenplay volumio[1228]: info: Loading plugin "services"... Oct 17 01:05:09 screenplay volumio[1228]: info: Loading plugin "volumio5onboarding"... Oct 17 01:05:09 screenplay sudo[1425]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 01:05:09 screenplay volumio[1228]: info: Loading plugin "alsa_controller"... Oct 17 01:05:09 screenplay sudo[1425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:09 screenplay sudo[1425]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:09 screenplay sudo[1437]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 01:05:09 screenplay sudo[1437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:09 screenplay sudo[1426]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 01:05:09 screenplay sudo[1426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:10 screenplay sudo[1426]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:10 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 01:05:10 screenplay volumio[1228]: info: Loading plugin "wizard"... Oct 17 01:05:10 screenplay volumio[1228]: info: Loading plugin "networkfs"... Oct 17 01:05:10 screenplay volumio[1228]: info: Starting Udev Watcher for removable devices Oct 17 01:05:10 screenplay sudo[1455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=laurens,password=Nieuwstraat11,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft '//192.168.111.2/media/muziek in de auto' /mnt/NAS/NAS Oct 17 01:05:10 screenplay sudo[1455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:10 screenplay sudo[1457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=LaurensG,password=Nieuwstraat1113?!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.111.235/Music /mnt/NAS/PiDrive Oct 17 01:05:10 screenplay sudo[1457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:10 screenplay volumio[1228]: info: Ignoring mount for partition: boot Oct 17 01:05:10 screenplay volumio[1228]: info: Ignoring mount for partition: volumio Oct 17 01:05:10 screenplay volumio[1228]: info: Ignoring mount for partition: volumio_data Oct 17 01:05:10 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 01:05:10 screenplay volumio[1228]: info: Loading plugin "volumio_command_line_client"... Oct 17 01:05:10 screenplay volumio[1228]: info: Loading plugin "upnp"... Oct 17 01:05:10 screenplay volumio[1228]: info: [1792191910777] Starting Upmpd Daemon Oct 17 01:05:10 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 01:05:10 screenplay volumio[1228]: info: Loading plugin "my_music"... Oct 17 01:05:10 screenplay volumio[1228]: info: Loading plugin "mpd"... Oct 17 01:05:10 screenplay kernel: netfs: FS-Cache loaded Oct 17 01:05:11 screenplay kernel: Key type cifs.spnego registered Oct 17 01:05:11 screenplay kernel: Key type cifs.idmap registered Oct 17 01:05:11 screenplay kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 17 01:05:11 screenplay kernel: CIFS: Attempting to mount //192.168.111.2/media/muziek in de auto Oct 17 01:05:12 screenplay kernel: CIFS: Attempting to mount //192.168.111.235/Music Oct 17 01:05:12 screenplay sudo[1455]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:12 screenplay sudo[1457]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:12 screenplay volumio[1228]: info: Loading plugin "upnp_browser"... Oct 17 01:05:13 screenplay sudo[1437]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:16 screenplay volumio[1228]: info: Starting UPNP Browser Oct 17 01:05:16 screenplay volumio[1228]: info: Loading plugin "alarm-clock"... Oct 17 01:05:17 screenplay systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 17 01:05:17 screenplay systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 17 01:05:17 screenplay systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 17 01:05:17 screenplay volumio[1228]: info: Loading plugin "airplay_emulation"... Oct 17 01:05:17 screenplay volumio[1228]: info: Starting Shairport Sync Oct 17 01:05:17 screenplay upmpdcli[1500]: Could not open config: /tmp/upmpdcli.conf Oct 17 01:05:17 screenplay volumio[1228]: info: Loading plugin "last_100"... Oct 17 01:05:17 screenplay systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 17 01:05:17 screenplay systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 17 01:05:17 screenplay volumio[1228]: info: Loading plugin "webradio"... Oct 17 01:05:17 screenplay volumio-remote-updater[678]: [2026-10-17 01:05:17] [connect] Successful connection Oct 17 01:05:17 screenplay volumio[1228]: info: Loading plugin "i2s_dacs"... Oct 17 01:05:17 screenplay volumio[1228]: info: Loading plugin "volumiodiscovery"... Oct 17 01:05:18 screenplay volumio[1228]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 01:05:18 screenplay node[1228]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 01:05:18 screenplay volumio[1228]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 01:05:18 screenplay volumio[1228]: *** WARNING *** For more information see Oct 17 01:05:18 screenplay volumio[1228]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 01:05:18 screenplay volumio[1228]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 01:05:18 screenplay volumio[1228]: *** WARNING *** For more information see Oct 17 01:05:18 screenplay node[1228]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 01:05:18 screenplay node[1228]: *** WARNING *** For more information see Oct 17 01:05:18 screenplay node[1228]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 01:05:18 screenplay node[1228]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 01:05:18 screenplay node[1228]: *** WARNING *** For more information see Oct 17 01:05:18 screenplay volumio[1228]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 01:05:18 screenplay volumio[1228]: info: Discovery: Started advertising with name: ScreenPlay Oct 17 01:05:18 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 01:05:18 screenplay volumio[1228]: info: Loading plugin "outputs"... Oct 17 01:05:18 screenplay volumio[1228]: info: Loading plugin "albumart"... Oct 17 01:05:18 screenplay volumio[1228]: info: Plugin example_plugin is not enabled Oct 17 01:05:18 screenplay volumio[1228]: info: Loading plugin "inputs"... Oct 17 01:05:18 screenplay volumio[1228]: info: Loading plugin "updater_comm"... Oct 17 01:05:19 screenplay volumio[1228]: info: Plugin mpdemulation is not enabled Oct 17 01:05:19 screenplay volumio[1228]: info: Loading plugin "rest_api"... Oct 17 01:05:19 screenplay volumio[1228]: info: Loading plugin "websocket"... Oct 17 01:05:19 screenplay volumio[1228]: info: Starting Socket.io Server version 1.7.4 Oct 17 01:05:19 screenplay volumio[1228]: info: Loading plugin "podcast"... Oct 17 01:05:21 screenplay volumio[1228]: info: ControllerPodcast::constructor Oct 17 01:05:21 screenplay volumio[1503]: Forking 3 albumart workers Oct 17 01:05:21 screenplay volumio[1228]: info: Loading plugin "Systeminfo"... Oct 17 01:05:28 screenplay volumio[1228]: info: Loading plugin "touch_display"... Oct 17 01:05:32 screenplay volumio[1228]: info: Applying required configuration parameters for plugin touch_display Oct 17 01:05:32 screenplay systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Oct 17 01:05:32 screenplay systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 17 01:05:32 screenplay systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 17 01:05:32 screenplay volumio-remote-updater[678]: [2026-10-17 01:05:32] [connect] Successful connection Oct 17 01:05:32 screenplay upmpdcli[1571]: Could not open config: /tmp/upmpdcli.conf Oct 17 01:05:32 screenplay systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 17 01:05:32 screenplay systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 17 01:05:34 screenplay volumio[1228]: info: Loading i18n strings for locale nl Oct 17 01:05:34 screenplay volumio[1228]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 17 01:05:34 screenplay volumio[1228]: Updating browse sources language Oct 17 01:05:35 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::initPlayerControls Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 01:05:36 screenplay volumio[1228]: Express server listening on port 3000 Oct 17 01:05:36 screenplay volumio[1228]: [Metrics] WebUI: 36s 986.82ms Oct 17 01:05:37 screenplay volumio[1228]: info: CoreStateMachine::resetVolumioState Oct 17 01:05:37 screenplay volumio[1228]: info: CoreStateMachine::getcurrentVolume Oct 17 01:05:37 screenplay volumio[1228]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 01:05:38 screenplay sudo[1583]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 01:05:38 screenplay sudo[1583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:38 screenplay sudo[1584]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 01:05:38 screenplay sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:38 screenplay sudo[1583]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:38 screenplay sudo[1584]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:38 screenplay volumio[1228]: info: Volumio Network Manager: Network status updated: 2 Oct 17 01:05:39 screenplay volumio[1528]: Starting albumart workers Oct 17 01:05:39 screenplay volumio[1529]: Starting albumart workers Oct 17 01:05:39 screenplay volumio[1527]: Starting albumart workers Oct 17 01:05:40 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.145 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 17 01:05:40 screenplay volumio[1228]: info: CoreStateMachine::pushState Oct 17 01:05:40 screenplay volumio[1228]: info: CorePlayQueue::getTrack 0 Oct 17 01:05:40 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:05:40 screenplay volumio[1228]: info: CoreCommandRouter::volumioPushState Oct 17 01:05:40 screenplay volumio[1228]: info: CoreStateMachine::updateTrackBlock Oct 17 01:05:40 screenplay volumio[1228]: info: CorePlayQueue::getTrackBlock Oct 17 01:05:40 screenplay volumio[1228]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 01:05:40 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 17 01:05:41 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 01:05:41 screenplay volumio[1228]: info: Reloading queue from file Oct 17 01:05:41 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.66 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 01:05:41 screenplay volumio[1228]: info: CoreStateMachine::setRepeat true single undefined Oct 17 01:05:41 screenplay volumio[1228]: info: CoreStateMachine::pushState Oct 17 01:05:41 screenplay volumio[1228]: info: CorePlayQueue::getTrack 0 Oct 17 01:05:41 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 01:05:41 screenplay volumio[1228]: info: CoreCommandRouter::volumioPushState Oct 17 01:05:41 screenplay volumio[1228]: info: CoreStateMachine::setRandom null Oct 17 01:05:41 screenplay volumio[1228]: info: CoreStateMachine::pushState Oct 17 01:05:41 screenplay volumio[1228]: info: CorePlayQueue::getTrack 0 Oct 17 01:05:41 screenplay volumio[1228]: info: CoreCommandRouter::volumioPushState Oct 17 01:05:41 screenplay volumio[1228]: info: Setting Device type: Raspberry PI Oct 17 01:05:42 screenplay volumio[1228]: info: Completed loading Core Plugins Oct 17 01:05:42 screenplay volumio[1228]: info: Preparing to generate the ALSA configuration file Oct 17 01:05:42 screenplay volumio[1228]: info: CoreStateMachine::pushState Oct 17 01:05:42 screenplay volumio[1228]: info: CorePlayQueue::getTrack 0 Oct 17 01:05:42 screenplay volumio[1228]: info: CoreCommandRouter::volumioPushState Oct 17 01:05:43 screenplay volumio[1228]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 17 01:05:43 screenplay volumio[1228]: info: Reading ALSA contributions from plugins. Oct 17 01:05:43 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 01:05:43 screenplay sudo[1617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 17 01:05:43 screenplay sudo[1617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:43 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.145 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 17 01:05:43 screenplay volumio[1228]: info: Discovery: adding 7320f2cc-8b2d-4226-aac4-50e9a68e98ae Oct 17 01:05:43 screenplay volumio[1228]: info: Discovery: Found device ScreenPlay Oct 17 01:05:43 screenplay volumio[1228]: info: CoreCommandRouter::volumioGetState Oct 17 01:05:43 screenplay volumio[1228]: info: CorePlayQueue::getTrack 0 Oct 17 01:05:44 screenplay volumio[1228]: info: Discovery: adding 0d7aee5a-d041-4018-9fde-1c4bfcbf7ab1 Oct 17 01:05:44 screenplay volumio[1228]: info: Discovery: Found device Zitruimte Oct 17 01:05:44 screenplay volumio[1228]: info: Discovery: Connecting to remote: 192.168.111.165 Oct 17 01:05:44 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.66 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 17 01:05:44 screenplay volumio[1228]: info: Discovery: this is already registered, 7320f2cc-8b2d-4226-aac4-50e9a68e98ae Oct 17 01:05:44 screenplay volumio[1228]: info: Discovery: Found device ScreenPlay Oct 17 01:05:44 screenplay volumio[1228]: info: CoreCommandRouter::volumioGetState Oct 17 01:05:44 screenplay volumio[1228]: info: CorePlayQueue::getTrack 0 Oct 17 01:05:45 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 17 01:05:45 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.145 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 17 01:05:46 screenplay volumio[1228]: info: Discovery: adding 61c37f6c-f1ad-4a88-83cf-2e415dbb333d Oct 17 01:05:46 screenplay volumio[1228]: info: Discovery: Found device Bureau Oct 17 01:05:46 screenplay volumio[1228]: info: Discovery: Connecting to remote: 192.168.111.66 Oct 17 01:05:46 screenplay volumio[1228]: info: Discovery: adding 7950dc36-0d31-4e9b-b819-2e36fad59d71 Oct 17 01:05:46 screenplay volumio[1228]: info: Discovery: Found device Bed Oct 17 01:05:46 screenplay volumio[1228]: info: Discovery: Connecting to remote: 192.168.111.145 Oct 17 01:05:46 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.145 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 17 01:05:46 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 17 01:05:46 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Oct 17 01:05:46 screenplay volumio[1228]: info: Discovery: Connected to remote: 192.168.111.165 Oct 17 01:05:46 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.66 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Oct 17 01:05:47 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.66 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Oct 17 01:05:47 screenplay volumio[1228]: info: Asound.conf file written Oct 17 01:05:47 screenplay volumio-remote-updater[678]: [2026-10-17 01:05:47] [connect] Successful connection Oct 17 01:05:48 screenplay systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Oct 17 01:05:48 screenplay systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 17 01:05:48 screenplay sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 01:05:48 screenplay sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:48 screenplay systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 17 01:05:48 screenplay sudo[1617]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:48 screenplay sudo[1636]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:48 screenplay volumio[1228]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 17 01:05:48 screenplay volumio[1228]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 17 01:05:48 screenplay volumio[1228]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 17 01:05:48 screenplay volumio[1228]: info: Output device has changed, restarting MPD Oct 17 01:05:48 screenplay volumio[1228]: info: Output device has changed, restarting Shairport Sync Oct 17 01:05:48 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:48 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 01:05:48 screenplay sudo[1657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 01:05:48 screenplay sudo[1657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:48 screenplay sudo[1655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 01:05:48 screenplay sudo[1655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:49 screenplay volumio[1228]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 01:05:49 screenplay volumio[1228]: info: ___________ START PLUGINS ___________ Oct 17 01:05:49 screenplay sudo[1655]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:49 screenplay volumio[1228]: info: ControllerMpd::onStart: Initializing MPD Oct 17 01:05:49 screenplay volumio[1228]: info: Creating MPD Configuration file Oct 17 01:05:49 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 01:05:49 screenplay volumio[1228]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 01:05:49 screenplay volumio[1228]: info: [1792191949344] CoreMusicLibrary::Adding element Media Servers Oct 17 01:05:49 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 01:05:49 screenplay volumio[1228]: info: UPNP Browser: Client initialized successfully Oct 17 01:05:49 screenplay sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 01:05:49 screenplay sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:49 screenplay systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 01:05:49 screenplay systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 01:05:49 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:49 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 01:05:49 screenplay sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 01:05:49 screenplay sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:49 screenplay systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:05:49 screenplay sudo[1673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 01:05:49 screenplay sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:49 screenplay sudo[1669]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:49 screenplay sudo[1671]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:50 screenplay sudo[1676]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 01:05:50 screenplay sudo[1676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 01:05:50 screenplay sudo[1676]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:50 screenplay systemd[1]: mpd.service: Deactivated successfully. Oct 17 01:05:50 screenplay systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 01:05:50 screenplay systemd[1]: mpd.socket: Deactivated successfully. Oct 17 01:05:50 screenplay systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 01:05:50 screenplay systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 01:05:50 screenplay volumio[1228]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 01:05:50 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:05:50 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 01:05:50 screenplay systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 01:05:50 screenplay systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 01:05:50 screenplay volumio[1228]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 01:05:50 screenplay volumio[1228]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 01:05:50 screenplay volumio[1228]: info: [1792191950476] CoreMusicLibrary::Adding element Last_100 Oct 17 01:05:50 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 01:05:50 screenplay volumio[1228]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 01:05:50 screenplay volumio[1228]: info: [1792191950595] CoreMusicLibrary::Adding element Webradio Oct 17 01:05:50 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 01:05:50 screenplay sudo[1692]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 01:05:50 screenplay sudo[1692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 01:05:50 screenplay sudo[1696]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 17 01:05:50 screenplay sudo[1692]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:50 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 01:05:50 screenplay volumio5-onboarding[1678]: time=2026-10-17T01:05:50.752+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 17 01:05:50 screenplay volumio[1228]: info: Initializing BBC Radios Oct 17 01:05:51 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 01:05:51 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 01:05:52 screenplay volumio[1228]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 01:05:52 screenplay volumio[1228]: info: [1792191952203] CoreMusicLibrary::Adding element Podcast Oct 17 01:05:52 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 01:05:52 screenplay volumio[1228]: Cannot find translation for source Podcast Oct 17 01:05:52 screenplay volumio[1228]: info: Loading i18n strings for locale nl Oct 17 01:05:52 screenplay volumio[1228]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 17 01:05:52 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 01:05:52 screenplay volumio[1228]: info: Volumio Calling Home Oct 17 01:05:53 screenplay sudo[1728]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Oct 17 01:05:53 screenplay sudo[1728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:53 screenplay sudo[1728]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:54 screenplay sudo[1734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 17 01:05:54 screenplay sudo[1734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:54 screenplay sudo[1736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 17 01:05:54 screenplay sudo[1736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:54 screenplay sudo[1738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 17 01:05:54 screenplay sudo[1738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:05:54 screenplay systemd[1]: Reloading. Oct 17 01:05:58 screenplay sudo[1738]: pam_unix(sudo:session): session closed for user root Oct 17 01:05:58 screenplay systemd[1]: Reloading. Oct 17 01:06:00 screenplay volumio5-onboarding[1678]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:33932->127.0.0.1:3000: i/o timeout Oct 17 01:06:02 screenplay systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 17 01:06:02 screenplay systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 17 01:06:02 screenplay systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 17 01:06:02 screenplay systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:02 screenplay systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:02 screenplay sudo[1736]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:02 screenplay volumio5-onboarding[1778]: time=2026-10-17T01:06:02.544+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 17 01:06:02 screenplay sudo[1734]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:02 screenplay volumio-remote-updater[678]: [2026-10-17 01:06:02] [connect] Successful connection Oct 17 01:06:03 screenplay mpd[1698]: 2026-10-17T01:06:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 01:06:04 screenplay systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 01:06:04 screenplay sudo[1657]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:04 screenplay sudo[1673]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:04 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.145 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Oct 17 01:06:05 screenplay volumio[1228]: info: touch_display: Backlight interface detected. Oct 17 01:06:05 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.145 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Oct 17 01:06:05 screenplay volumio[1228]: info: Discovery: Connected to remote: 192.168.111.66 Oct 17 01:06:05 screenplay volumio[1228]: info: Discovery: Connected to remote: 192.168.111.145 Oct 17 01:06:05 screenplay volumio[1228]: info: touch_display: systemctl daemon-reload succeeded. Oct 17 01:06:06 screenplay volumio[1228]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 17 01:06:06 screenplay volumio[1228]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 17 01:06:06 screenplay sudo[1806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 17 01:06:06 screenplay sudo[1806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:06:06 screenplay volumio[1228]: info: MPD Permissions set Oct 17 01:06:06 screenplay volumio[1228]: info: MPD Permissions set Oct 17 01:06:06 screenplay systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Oct 17 01:06:06 screenplay sudo[1806]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:06 screenplay volumio[1228]: info: Upmpdcli Daemon Started Oct 17 01:06:06 screenplay startx[1835]: X.Org X Server 1.21.1.7 Oct 17 01:06:06 screenplay startx[1835]: X Protocol Version 11, Revision 0 Oct 17 01:06:06 screenplay startx[1835]: Current Operating System: Linux screenplay 6.12.74-v7+ #1948 SMP Mon Mar 2 11:25:27 GMT 2026 armv7l Oct 17 01:06:06 screenplay startx[1835]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Oct 17 01:06:06 screenplay startx[1835]: xorg-server 2:21.1.7-3+rpt3+deb12u13 (https://www.debian.org/support) Oct 17 01:06:06 screenplay startx[1835]: Current version of pixman: 0.44.0 Oct 17 01:06:06 screenplay startx[1835]: Before reporting problems, check http://wiki.x.org Oct 17 01:06:06 screenplay startx[1835]: to make sure that you have the latest version. Oct 17 01:06:06 screenplay startx[1835]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 17 01:06:06 screenplay startx[1835]: (++) from command line, (!!) notice, (II) informational, Oct 17 01:06:06 screenplay startx[1835]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 17 01:06:06 screenplay startx[1835]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Oct 17 01:06:06 2026 Oct 17 01:06:06 screenplay startx[1835]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 17 01:06:06 screenplay startx[1835]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 17 01:06:06 screenplay volumio[1228]: info: Discovery: Disconnected from remote: 192.168.111.165 Oct 17 01:06:06 screenplay sudo[1809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Oct 17 01:06:06 screenplay sudo[1809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:06:07 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Oct 17 01:06:07 screenplay volumio[1228]: info: MPD running with PID1698 Oct 17 01:06:07 screenplay volumio[1228]: ,establishing connection Oct 17 01:06:07 screenplay volumio[1228]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 17 01:06:07 screenplay volumio[1228]: info: touch_display: Volumio Kiosk started. Oct 17 01:06:07 screenplay volumio[1228]: info: CoreCommandRouter::volumioGetState Oct 17 01:06:07 screenplay volumio[1228]: info: CorePlayQueue::getTrack 0 Oct 17 01:06:07 screenplay volumio[1228]: info: Completed starting Core Plugins Oct 17 01:06:07 screenplay volumio[1228]: info: ------------------------------------------- Oct 17 01:06:07 screenplay volumio[1228]: info: ----- MyVolumio plugins startup ---- Oct 17 01:06:07 screenplay volumio[1228]: info: ------------------------------------------- Oct 17 01:06:07 screenplay volumio[1228]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 17 01:06:07 screenplay sudo[1809]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:07 screenplay volumio[1228]: info: Volumio called home Oct 17 01:06:07 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 01:06:07 screenplay volumio[1228]: info: Systeminfo: --[object Object] Oct 17 01:06:07 screenplay volumio[1228]: info: Systeminfo web UI listening on port 12334 Oct 17 01:06:11 screenplay volumio[1228]: error: MPD error: The expression evaluated to a falsy value: Oct 17 01:06:11 screenplay volumio[1228]: assert.ok(self.idling) Oct 17 01:06:11 screenplay volumio[1228]: error: The expression evaluated to a falsy value: Oct 17 01:06:11 screenplay volumio[1228]: assert.ok(self.idling) Oct 17 01:06:11 screenplay volumio[1228]: error: MPD error: The expression evaluated to a falsy value: Oct 17 01:06:11 screenplay volumio[1228]: assert.ok(self.idling) Oct 17 01:06:11 screenplay volumio[1228]: error: The expression evaluated to a falsy value: Oct 17 01:06:11 screenplay volumio[1228]: assert.ok(self.idling) Oct 17 01:06:12 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.165 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Oct 17 01:06:12 screenplay volumio[1228]: info: touch_display: IframeKeyboardBridge extension installed successfully Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 01:06:12 screenplay volumio5-onboarding[1778]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:54672->127.0.0.1:3000: i/o timeout Oct 17 01:06:12 screenplay systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 17 01:06:12 screenplay systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 01:06:12 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:13 screenplay systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Oct 17 01:06:13 screenplay systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:13 screenplay systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:13 screenplay volumio5-onboarding[1927]: time=2026-10-17T01:06:13.224+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 17 01:06:13 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.66 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 17 01:06:13 screenplay volumio[1228]: info: touch_display: X display number found: 0 Oct 17 01:06:14 screenplay volumio[1228]: error: updateQueue error: null Oct 17 01:06:14 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.145 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Oct 17 01:06:14 screenplay volumio[1228]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 17 01:06:15 screenplay volumio[1228]: info: touch_display: Raspberry Pi Foundation touch screen detected. Oct 17 01:06:15 screenplay sudo[1976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 17 01:06:15 screenplay sudo[1976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:06:15 screenplay sudo[1974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Oct 17 01:06:15 screenplay sudo[1974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:06:15 screenplay sudo[1976]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:15 screenplay sudo[1974]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:15 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.145 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Oct 17 01:06:15 screenplay volumio[1228]: info: touch_display: X display number found: 0 Oct 17 01:06:16 screenplay sudo[1980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 17 01:06:16 screenplay sudo[1980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:06:16 screenplay volumio[1228]: info: Starting Shairport Sync Oct 17 01:06:16 screenplay sudo[1980]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:16 screenplay volumio[1228]: info: Starting Shairport Sync Oct 17 01:06:16 screenplay volumio[1228]: info: Starting Shairport Sync Oct 17 01:06:16 screenplay sudo[1985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 01:06:16 screenplay sudo[1985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:06:16 screenplay sudo[1992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 01:06:16 screenplay sudo[1992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:06:16 screenplay sudo[1991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 01:06:16 screenplay sudo[1991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:06:16 screenplay systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 01:06:16 screenplay systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 01:06:16 screenplay systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 01:06:16 screenplay systemd[1]: shairport-sync.service: Consumed 2.338s CPU time. Oct 17 01:06:16 screenplay systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 01:06:16 screenplay sudo[1985]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:16 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.66 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Oct 17 01:06:17 screenplay systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 01:06:17 screenplay systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 01:06:17 screenplay systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 01:06:17 screenplay systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 01:06:17 screenplay systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 01:06:17 screenplay sudo[1991]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:17 screenplay systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 01:06:17 screenplay systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 01:06:17 screenplay systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 01:06:17 screenplay volumio[1228]: info: CoreCommandRouter::volumioGetState Oct 17 01:06:17 screenplay volumio[1228]: info: CorePlayQueue::getTrack 0 Oct 17 01:06:17 screenplay sudo[1992]: pam_unix(sudo:session): session closed for user root Oct 17 01:06:17 screenplay volumio[1228]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 17 01:06:17 screenplay volumio[1228]: info: touch_display: File permissions for backlight brightness control set. Oct 17 01:06:17 screenplay volumio[1228]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 17 01:06:17 screenplay volumio-remote-updater[678]: [2026-10-17 01:06:17] [connect] Successful connection Oct 17 01:06:19 screenplay volumio[1228]: verbose: New Socket.io Connection to 192.168.111.51:3000 from 192.168.111.66 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Oct 17 01:06:20 screenplay volumio[1228]: info: Discovery: Connected to remote: 192.168.111.165 Oct 17 01:06:20 screenplay volumio[1228]: info: Shairport-Sync Started Oct 17 01:06:20 screenplay volumio[1228]: Error adding Membership: Error: addMembership EINVAL Oct 17 01:06:20 screenplay volumio[1228]: info: Shairport-Sync Started Oct 17 01:06:20 screenplay volumio[1228]: info: Shairport-Sync Started Oct 17 01:06:20 screenplay volumio[1228]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Oct 17 01:06:23 screenplay volumio5-onboarding[1927]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:57662->127.0.0.1:3000: i/o timeout Oct 17 01:06:23 screenplay systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 17 01:06:23 screenplay systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 17 01:06:23 screenplay systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Oct 17 01:06:23 screenplay systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:23 screenplay systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:23 screenplay volumio5-onboarding[2073]: time=2026-10-17T01:06:23.754+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 17 01:06:25 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 01:06:25 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 01:06:25 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 01:06:26 screenplay volumio[1228]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 17 01:06:32 screenplay volumio-remote-updater[678]: [2026-10-17 01:06:32] [connect] Successful connection Oct 17 01:06:33 screenplay volumio5-onboarding[2073]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:50150->127.0.0.1:3000: i/o timeout Oct 17 01:06:33 screenplay systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 17 01:06:33 screenplay systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 17 01:06:33 screenplay systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Oct 17 01:06:34 screenplay systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:34 screenplay systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:34 screenplay volumio5-onboarding[2136]: time=2026-10-17T01:06:34.330+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 17 01:06:41 screenplay volumio[1228]: error: MyVolumio Plugin failed to start in a timely fashion Oct 17 01:06:41 screenplay volumio[1228]: [Metrics] CommandRouter: 98s 657.86ms Oct 17 01:06:41 screenplay volumio[1228]: info: CoreCommandRouter::volumiosetStartupVolume Oct 17 01:06:41 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 01:06:41 screenplay volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 01:06:41 screenplay volumio[1228]: info: CoreCommandRouter::Close All Modals sent Oct 17 01:06:41 screenplay volumio[1228]: info: CoreCommandRouter::Close All Modals sent Oct 17 01:06:42 screenplay volumio[1228]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 01:06:42 screenplay volumio[1228]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 17 01:06:42 screenplay volumio[1228]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 17 01:06:42 screenplay volumio[1228]: at new NodeError (node:internal/errors:405:5) Oct 17 01:06:42 screenplay volumio[1228]: at assert (node:internal/assert:14:11) Oct 17 01:06:42 screenplay volumio[1228]: at internalConnectMultiple (node:net:1118:3) Oct 17 01:06:42 screenplay volumio[1228]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 17 01:06:42 screenplay volumio[1228]: at listOnTimeout (node:internal/timers:575:11) Oct 17 01:06:42 screenplay volumio[1228]: at process.processTimers (node:internal/timers:514:7) { Oct 17 01:06:42 screenplay volumio[1228]: code: 'ERR_INTERNAL_ASSERTION' Oct 17 01:06:42 screenplay volumio[1228]: } Oct 17 01:06:42 screenplay volumio[1228]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 01:06:44 screenplay volumio5-onboarding[2136]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:44670->127.0.0.1:3000: i/o timeout Oct 17 01:06:44 screenplay systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 17 01:06:44 screenplay systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 17 01:06:44 screenplay systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Oct 17 01:06:44 screenplay systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:44 screenplay systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:44 screenplay volumio5-onboarding[2172]: time=2026-10-17T01:06:44.654+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 17 01:06:47 screenplay volumio-remote-updater[678]: [2026-10-17 01:06:47] [connect] Successful connection Oct 17 01:06:54 screenplay sudo[2197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 01:05' Oct 17 01:06:54 screenplay sudo[2197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 01:06:54 screenplay volumio5-onboarding[2172]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:37724->127.0.0.1:3000: i/o timeout Oct 17 01:06:54 screenplay systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 17 01:06:54 screenplay systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 17 01:06:54 screenplay systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 6. Oct 17 01:06:55 screenplay systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:55 screenplay systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 17 01:06:55 screenplay volumio5-onboarding[2199]: time=2026-10-17T01:06:55.162+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z 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"