-- Logs begin at Sat 2025-09-13 18:29:30 CEST, end at Sat 2025-09-13 18:31:52 CEST. -- Sep 13 18:30:00 volumio volumio[825]: info: Plugin folders cleanup Sep 13 18:30:00 volumio volumio[825]: info: Scanning into folder /volumio/app/plugins/ Sep 13 18:30:00 volumio volumio[825]: info: Scanning category audio_interface Sep 13 18:30:00 volumio volumio[825]: info: Scanning category miscellanea Sep 13 18:30:00 volumio volumio[825]: info: Scanning category music_service Sep 13 18:30:00 volumio volumio[825]: info: Scanning category plugins.json Sep 13 18:30:00 volumio volumio[825]: info: Scanning category system_controller Sep 13 18:30:00 volumio volumio[825]: info: Scanning category user_interface Sep 13 18:30:00 volumio volumio[825]: info: Scanning into folder /data/plugins/ Sep 13 18:30:00 volumio volumio[825]: info: Scanning category system_hardware Sep 13 18:30:00 volumio volumio[825]: info: Scanning category user_interface Sep 13 18:30:00 volumio volumio[825]: info: Plugin folders cleanup completed Sep 13 18:30:00 volumio volumio[825]: info: ------------------------------------------- Sep 13 18:30:00 volumio volumio[825]: info: ----- Core plugins startup ---- Sep 13 18:30:00 volumio volumio[825]: info: ------------------------------------------- Sep 13 18:30:00 volumio volumio[825]: info: Loading plugins from folder /volumio/app/plugins/ Sep 13 18:30:00 volumio volumio[825]: info: Adding plugin upnp to MyMusic Plugins Sep 13 18:30:00 volumio volumio[825]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 13 18:30:00 volumio volumio[825]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 13 18:30:00 volumio volumio[825]: info: Loading plugins from folder /data/plugins/ Sep 13 18:30:00 volumio volumio[825]: info: Loading plugin "system"... Sep 13 18:30:00 volumio volumio-remote-updater[521]: [2025-09-13 18:30:00] [connect] Successful connection Sep 13 18:30:00 volumio smbd[855]: [2025/09/13 18:30:00.104278, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 13 18:30:00 volumio systemd[1]: Started Samba SMB Daemon. Sep 13 18:30:00 volumio volumio[825]: info: Loading plugin "appearance"... Sep 13 18:30:00 volumio smbd[855]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 13 18:30:00 volumio systemd[1]: Reached target Multi-User System. Sep 13 18:30:00 volumio systemd[1]: Reached target Graphical Interface. Sep 13 18:30:00 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 13 18:30:00 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 13 18:30:00 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 13 18:30:00 volumio systemd[1]: Startup finished in 10.532s (kernel) + 32.191s (userspace) = 42.723s. Sep 13 18:30:01 volumio volumio[825]: info: Loading plugin "network"... Sep 13 18:30:01 volumio volumio[825]: info: Refreshing Cached IP Addresses Sep 13 18:30:01 volumio sudo[870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 13 18:30:01 volumio sudo[870]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:01 volumio volumio[825]: info: Loading plugin "services"... Sep 13 18:30:01 volumio sudo[872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 13 18:30:01 volumio sudo[870]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:01 volumio sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:01 volumio volumio[825]: info: Loading plugin "alsa_controller"... Sep 13 18:30:01 volumio sudo[872]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:01 volumio sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 13 18:30:01 volumio sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:01 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 13 18:30:01 volumio volumio[825]: info: Loading plugin "wizard"... Sep 13 18:30:01 volumio volumio[825]: info: Loading plugin "networkfs"... Sep 13 18:30:01 volumio sudo[883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=julien.barrillet@gmail.com,password=hbMNpgbsVDRR8@H,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.254/Cecile/Multimedia/Music /mnt/NAS/Cecile Sep 13 18:30:01 volumio sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:01 volumio volumio[825]: info: Starting Udev Watcher for removable devices Sep 13 18:30:01 volumio sudo[888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.254/Cecile/Multimedia/Music Enfant/ /mnt/NAS/Cecile Sep 13 18:30:01 volumio sudo[888]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:01 volumio sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=julien.barrillet@gmail.com,password=hbMNpgbsVDRR8@H,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.254/Cecile/Multimedia/Music Enfant /mnt/NAS/Cecile Sep 13 18:30:01 volumio sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:01 volumio volumio[825]: info: Ignoring mount for partition: boot Sep 13 18:30:01 volumio volumio[825]: info: Ignoring mount for partition: volumio Sep 13 18:30:01 volumio volumio[825]: info: Ignoring mount for partition: volumio_data Sep 13 18:30:01 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 13 18:30:01 volumio volumio[825]: info: Loading plugin "volumio_command_line_client"... Sep 13 18:30:01 volumio volumio[825]: info: Loading plugin "upnp"... Sep 13 18:30:01 volumio volumio[825]: info: [1757781001929] Starting Upmpd Daemon Sep 13 18:30:01 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 13 18:30:01 volumio volumio[825]: info: Loading plugin "my_music"... Sep 13 18:30:01 volumio volumio[825]: info: Loading plugin "mpd"... Sep 13 18:30:02 volumio kernel: Key type cifs.spnego registered Sep 13 18:30:02 volumio kernel: Key type cifs.idmap registered Sep 13 18:30:02 volumio 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. Sep 13 18:30:02 volumio kernel: CIFS: Attempting to mount \\192.168.1.254\Cecile Sep 13 18:30:02 volumio kernel: CIFS: Attempting to mount \\192.168.1.254\Cecile Sep 13 18:30:02 volumio sudo[888]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:02 volumio volumio[825]: info: Loading plugin "upnp_browser"... Sep 13 18:30:02 volumio sudo[885]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:02 volumio kernel: CIFS: Attempting to mount \\192.168.1.254\Cecile Sep 13 18:30:02 volumio sudo[883]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:02 volumio volumio[825]: info: Loading plugin "alarm-clock"... Sep 13 18:30:03 volumio volumio[825]: info: Loading plugin "airplay_emulation"... Sep 13 18:30:03 volumio volumio[825]: info: Starting Shairport Sync Sep 13 18:30:03 volumio volumio[825]: info: Loading plugin "last_100"... Sep 13 18:30:03 volumio volumio[825]: info: Loading plugin "webradio"... Sep 13 18:30:03 volumio volumio[825]: info: Loading plugin "i2s_dacs"... Sep 13 18:30:03 volumio volumio[825]: info: I2S DAC not set, start Auto-detection Sep 13 18:30:03 volumio volumio[825]: info: Loading plugin "volumiodiscovery"... Sep 13 18:30:03 volumio volumio[825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 13 18:30:03 volumio volumio[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 13 18:30:03 volumio volumio[825]: *** WARNING *** For more information see Sep 13 18:30:03 volumio node[825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 13 18:30:03 volumio volumio[825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 13 18:30:03 volumio volumio[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 13 18:30:03 volumio volumio[825]: *** WARNING *** For more information see Sep 13 18:30:03 volumio node[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 13 18:30:03 volumio node[825]: *** WARNING *** For more information see Sep 13 18:30:03 volumio node[825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 13 18:30:03 volumio node[825]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 13 18:30:03 volumio node[825]: *** WARNING *** For more information see Sep 13 18:30:03 volumio volumio[825]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 13 18:30:03 volumio volumio[825]: info: Discovery: Started advertising with name: Volumio Sep 13 18:30:03 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 13 18:30:03 volumio volumio[825]: info: Loading plugin "gpio-buttons"... Sep 13 18:30:04 volumio volumio[825]: info: GPIO-Buttons initialized Sep 13 18:30:04 volumio volumio[825]: info: Loading plugin "outputs"... Sep 13 18:30:04 volumio volumio[825]: info: Loading plugin "albumart"... Sep 13 18:30:04 volumio volumio[825]: info: Plugin example_plugin is not enabled Sep 13 18:30:04 volumio volumio[825]: info: Loading plugin "inputs"... Sep 13 18:30:04 volumio volumio[825]: info: Loading plugin "updater_comm"... Sep 13 18:30:04 volumio volumio[825]: info: Plugin mpdemulation is not enabled Sep 13 18:30:04 volumio volumio[825]: info: Loading plugin "rest_api"... Sep 13 18:30:04 volumio volumio[825]: info: Loading plugin "websocket"... Sep 13 18:30:04 volumio volumio[825]: info: Loading plugin "gpio_control"... Sep 13 18:30:04 volumio volumio[825]: Forking 3 albumart workers Sep 13 18:30:04 volumio volumio[825]: gpiox_arm32 V2.1.0 Sep 13 18:30:04 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 13 18:30:05 volumio volumio[825]: info: Applying required configuration parameters for plugin gpio_control Sep 13 18:30:05 volumio sudo[875]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:05 volumio volumio[825]: info: Loading plugin "touch_display"... Sep 13 18:30:05 volumio volumio[825]: Starting albumart workers Sep 13 18:30:05 volumio volumio[825]: Starting albumart workers Sep 13 18:30:05 volumio volumio[825]: Starting albumart workers Sep 13 18:30:05 volumio sh[380]: timed out Sep 13 18:30:05 volumio dhcpcd[428]: timed out Sep 13 18:30:05 volumio sh[380]: dhcpcd exited Sep 13 18:30:05 volumio dhcpcd[428]: dhcpcd exited Sep 13 18:30:05 volumio sh[380]: ifup: failed to bring up eth0 Sep 13 18:30:05 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 13 18:30:05 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 13 18:30:05 volumio volumio[825]: info: Applying required configuration parameters for plugin touch_display Sep 13 18:30:06 volumio volumio[825]: info: Loading i18n strings for locale fr Sep 13 18:30:06 volumio volumio[825]: Updating browse sources language Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::initPlayerControls Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 18:30:06 volumio volumio[825]: Express server listening on port 3000 Sep 13 18:30:06 volumio volumio[825]: [Metrics] WebUI: 8s 751.16ms Sep 13 18:30:06 volumio volumio[825]: info: CoreStateMachine::resetVolumioState Sep 13 18:30:06 volumio volumio[825]: info: CoreStateMachine::getcurrentVolume Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::volumioRetrievevolume Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:30:06 volumio volumio[825]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:142.0) Gecko/20100101 Firefox/142.0 Total Clients: 1 Sep 13 18:30:06 volumio volumio[825]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:142.0) Gecko/20100101 Firefox/142.0 Total Clients: 2 Sep 13 18:30:06 volumio volumio[825]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:142.0) Gecko/20100101 Firefox/142.0 Total Clients: 3 Sep 13 18:30:06 volumio volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Sep 13 18:30:06 volumio volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Sep 13 18:30:06 volumio volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Sep 13 18:30:06 volumio volumio[825]: info: Reloading queue from file Sep 13 18:30:06 volumio volumio[825]: info: VolumeController:: Volume=100 Mute =false Sep 13 18:30:06 volumio volumio[825]: info: CoreStateMachine::pushState Sep 13 18:30:06 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::volumioPushState Sep 13 18:30:06 volumio volumio[825]: info: CoreStateMachine::updateTrackBlock Sep 13 18:30:06 volumio volumio[825]: info: CorePlayQueue::getTrackBlock Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::volumioRetrievevolume Sep 13 18:30:06 volumio volumio[825]: info: Setting Device type: Raspberry PI Sep 13 18:30:06 volumio volumio[825]: info: CoreStateMachine::setRepeat null single undefined Sep 13 18:30:06 volumio volumio[825]: info: CoreStateMachine::pushState Sep 13 18:30:06 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::volumioPushState Sep 13 18:30:06 volumio volumio[825]: info: CoreStateMachine::setRandom null Sep 13 18:30:06 volumio volumio[825]: info: CoreStateMachine::pushState Sep 13 18:30:06 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::volumioPushState Sep 13 18:30:06 volumio volumio[825]: info: Discovery: adding 0d3fc09b-52b0-42fd-b197-1192af519df0 Sep 13 18:30:06 volumio volumio[825]: info: Discovery: Found device Volumio Sep 13 18:30:06 volumio volumio[825]: info: CoreCommandRouter::volumioGetState Sep 13 18:30:06 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:07 volumio volumio[825]: info: VolumeController:: Volume=100 Mute =false Sep 13 18:30:07 volumio volumio[825]: info: CoreStateMachine::pushState Sep 13 18:30:07 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:07 volumio volumio[825]: info: CoreCommandRouter::volumioPushState Sep 13 18:30:08 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Sep 13 18:30:09 volumio volumio[825]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:142.0) Gecko/20100101 Firefox/142.0 Total Clients: 7 Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::volumioGetState Sep 13 18:30:09 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::volumioGetState Sep 13 18:30:09 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 18:30:09 volumio volumio[825]: info: Received Get System Info Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 18:30:09 volumio volumio[825]: info: Discovery: Getting this device information Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::volumioGetState Sep 13 18:30:09 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 18:30:09 volumio volumio[825]: info: CoreCommandRouter::volumioGetState Sep 13 18:30:09 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:09 volumio volumio[825]: info: Listing playlists Sep 13 18:30:11 volumio sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 13 18:30:11 volumio sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:11 volumio sudo[993]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:11 volumio sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 13 18:30:11 volumio sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:11 volumio sudo[995]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:12 volumio sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 13 18:30:12 volumio sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 13 18:30:12 volumio sudo[999]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:12 volumio volumio[825]: info: Upmpdcli Daemon Started Sep 13 18:30:12 volumio volumio[825]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 13 18:30:15 volumio volumio-remote-updater[521]: [2025-09-13 18:30:15] [connect] Successful connection Sep 13 18:30:15 volumio volumio-remote-updater[521]: [2025-09-13 18:30:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757781015 101 Sep 13 18:30:15 volumio volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 8 Sep 13 18:30:16 volumio volumio[825]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 13 18:30:22 volumio volumio[825]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 13 18:30:32 volumio volumio[825]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 13 18:30:36 volumio volumio[825]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Sep 13 18:30:36 volumio volumio[825]: info: Preparing to generate the ALSA configuration file Sep 13 18:30:36 volumio volumio[825]: info: Asound.conf file unchanged, so no further update is needed Sep 13 18:30:36 volumio volumio[825]: info: Output device has changed, restarting MPD Sep 13 18:30:36 volumio volumio[825]: info: Output device has changed, restarting Shairport Sync Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:30:36 volumio sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 13 18:30:36 volumio sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:36 volumio sudo[1003]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:36 volumio sudo[1005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 13 18:30:36 volumio sudo[1005]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:36 volumio volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 13 18:30:36 volumio volumio[825]: info: ___________ START PLUGINS ___________ Sep 13 18:30:36 volumio volumio[825]: info: ControllerMpd::onStart: Initializing MPD Sep 13 18:30:36 volumio volumio[825]: info: Creating MPD Configuration file Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 13 18:30:36 volumio volumio[825]: info: [1757781036257] CoreMusicLibrary::Adding element Serveurs Média Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:30:36 volumio sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 13 18:30:36 volumio sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:36 volumio systemd[1]: Listening on mpd.socket. Sep 13 18:30:36 volumio systemd[1]: Starting Music Player Daemon... Sep 13 18:30:36 volumio sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 13 18:30:36 volumio sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:36 volumio sudo[1013]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:36 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 13 18:30:36 volumio systemd[1]: mpd.service: Succeeded. Sep 13 18:30:36 volumio systemd[1]: Stopped Music Player Daemon. Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:30:36 volumio systemd[1]: Starting Music Player Daemon... Sep 13 18:30:36 volumio volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 13 18:30:36 volumio volumio[825]: info: [1757781036400] CoreMusicLibrary::Adding element Last_100 Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 13 18:30:36 volumio volumio[825]: info: [1757781036404] CoreMusicLibrary::Adding element Webradio Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 13 18:30:36 volumio volumio[825]: info: GPIO-Buttons: Reading config and creating triggers... Sep 13 18:30:36 volumio volumio[825]: info: GPIO-Buttons: playPause on pin 17 Sep 13 18:30:36 volumio volumio[825]: info: GPIO-Buttons: volumeUp on pin 18 Sep 13 18:30:36 volumio volumio[825]: info: GPIO-Buttons: volumeDown on pin 22 Sep 13 18:30:36 volumio volumio[825]: info: GPIO-Buttons: previous on pin 23 Sep 13 18:30:36 volumio volumio[825]: info: GPIO-Buttons: next on pin 27 Sep 13 18:30:36 volumio volumio[825]: info: GPIO-Buttons: shutdown on pin 3 Sep 13 18:30:36 volumio volumio[825]: info: [GPIO_Control] Reading config and creating GPIOs Sep 13 18:30:36 volumio volumio[825]: info: [GPIO_Control] On systemStartup will set GPIO 16 to On Sep 13 18:30:36 volumio volumio[825]: info: Loading i18n strings for locale fr Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 13 18:30:36 volumio volumio[825]: info: Volumio Calling Home Sep 13 18:30:36 volumio volumio[825]: info: GPIO-Buttons started Sep 13 18:30:36 volumio volumio[825]: info: [GPIO_Control] GPIOs created Sep 13 18:30:36 volumio volumio[825]: info: [GPIO_Control] Handling event: systemStartup Sep 13 18:30:36 volumio volumio[825]: info: [GPIO_Control] *** systemStartup *** Sep 13 18:30:36 volumio sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 13 18:30:36 volumio sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:36 volumio sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 13 18:30:36 volumio sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:36 volumio sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 13 18:30:36 volumio sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:36 volumio volumio[825]: info: [GPIO_Control] Turning GPIO 16 On (systemStartup) Sep 13 18:30:36 volumio volumio[825]: info: touch_display: Backlight interface detected. Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::volumioGetState Sep 13 18:30:36 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:36 volumio volumio[825]: info: MPD Permissions set Sep 13 18:30:36 volumio volumio[825]: info: MPD Permissions set Sep 13 18:30:36 volumio systemd[1]: Reloading. Sep 13 18:30:36 volumio volumio[825]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:36 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:30:37 volumio volumio[825]: info: touch_display: Raspberry Pi Foundation touch screen detected. Sep 13 18:30:37 volumio sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 13 18:30:37 volumio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:37 volumio sudo[1139]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:37 volumio volumio[825]: info: Starting Shairport Sync Sep 13 18:30:37 volumio volumio[825]: info: Starting Shairport Sync Sep 13 18:30:37 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 13 18:30:37 volumio volumio[825]: info: Starting Shairport Sync Sep 13 18:30:37 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 13 18:30:37 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:37 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Sep 13 18:30:37 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:37 volumio sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 13 18:30:37 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:37 volumio sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:37 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 13 18:30:37 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:37 volumio volumio[825]: info: touch_display: File permissions for backlight brightness control set. Sep 13 18:30:37 volumio volumio[825]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Sep 13 18:30:37 volumio volumio[825]: info: Volumio called home Sep 13 18:30:37 volumio volumio[825]: info: CoreCommandRouter::volumioGetState Sep 13 18:30:37 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:37 volumio volumio[825]: info: [GPIO_Control] Status changed: stop Sep 13 18:30:37 volumio volumio[825]: info: [GPIO_Control] Handling event: musicStop Sep 13 18:30:37 volumio volumio[825]: info: [GPIO_Control] Handling event: repeatAllOff Sep 13 18:30:37 volumio volumio[825]: info: [GPIO_Control] Handling event: repeatOff Sep 13 18:30:37 volumio volumio[825]: info: [GPIO_Control] Status changed: stop Sep 13 18:30:37 volumio sudo[1115]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:37 volumio volumio[825]: info: touch_display: systemctl daemon-reload succeeded. Sep 13 18:30:37 volumio sudo[1109]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:37 volumio systemd[1]: Reloading. Sep 13 18:30:37 volumio volumio[825]: info: touch_display: systemctl stop getty@tty1.service succeeded. Sep 13 18:30:37 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 13 18:30:37 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:38 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 13 18:30:38 volumio sudo[1111]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:38 volumio volumio[825]: info: touch_display: systemctl disable getty@tty1.service succeeded. Sep 13 18:30:38 volumio mpd[1019]: Sep 13 18:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 13 18:30:38 volumio systemd[1]: Started Volumio Kiosk. Sep 13 18:30:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 13 18:30:38 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:38 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 13 18:30:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 13 18:30:38 volumio volumio[825]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Sep 13 18:30:38 volumio volumio[825]: info: touch_display: Volumio Kiosk started. Sep 13 18:30:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 13 18:30:38 volumio volumio[825]: info: CoreCommandRouter::volumioGetState Sep 13 18:30:38 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:38 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:38 volumio sudo[1147]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:38 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:38 volumio volumio[825]: info: CoreCommandRouter::volumioGetState Sep 13 18:30:38 volumio volumio[825]: info: CorePlayQueue::getTrack 0 Sep 13 18:30:38 volumio volumio[825]: info: Shairport-Sync Started Sep 13 18:30:38 volumio volumio[825]: Error adding Membership: Error: addMembership EINVAL Sep 13 18:30:38 volumio volumio[825]: info: Shairport-Sync Started Sep 13 18:30:38 volumio volumio[825]: info: Shairport-Sync Started Sep 13 18:30:38 volumio volumio[825]: info: touch_display: X display number found: Sep 13 18:30:39 volumio volumio[825]: info: touch_display: X display number found: 0 Sep 13 18:30:39 volumio systemd[1]: Started Music Player Daemon. Sep 13 18:30:39 volumio sudo[1015]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:39 volumio sudo[1005]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:39 volumio startx[1170]: X.Org X Server 1.20.4 Sep 13 18:30:39 volumio startx[1170]: X Protocol Version 11, Revision 0 Sep 13 18:30:39 volumio startx[1170]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Sep 13 18:30:39 volumio startx[1170]: Current Operating System: Linux volumio 6.1.69-v7+ #1710 SMP Thu Dec 21 13:14:13 GMT 2023 armv7l Sep 13 18:30:39 volumio startx[1170]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 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=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 Sep 13 18:30:39 volumio startx[1170]: Build Date: 04 April 2023 07:50:56AM Sep 13 18:30:39 volumio startx[1170]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Sep 13 18:30:39 volumio startx[1170]: Current version of pixman: 0.36.0 Sep 13 18:30:39 volumio startx[1170]: Before reporting problems, check http://wiki.x.org Sep 13 18:30:39 volumio startx[1170]: to make sure that you have the latest version. Sep 13 18:30:39 volumio startx[1170]: Markers: (--) probed, (**) from config file, (==) default setting, Sep 13 18:30:39 volumio startx[1170]: (++) from command line, (!!) notice, (II) informational, Sep 13 18:30:39 volumio startx[1170]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Sep 13 18:30:39 volumio startx[1170]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Sep 13 18:30:39 2025 Sep 13 18:30:39 volumio startx[1170]: (==) Using config directory: "/etc/X11/xorg.conf.d" Sep 13 18:30:39 volumio startx[1170]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Sep 13 18:30:39 volumio volumio[825]: info: Completed starting Core Plugins Sep 13 18:30:39 volumio volumio[825]: info: ------------------------------------------- Sep 13 18:30:39 volumio volumio[825]: info: ----- MyVolumio plugins startup ---- Sep 13 18:30:39 volumio volumio[825]: info: ------------------------------------------- Sep 13 18:30:39 volumio volumio[825]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 13 18:30:39 volumio volumio[825]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 13 18:30:39 volumio volumio[825]: info: MPD running with PID1019 Sep 13 18:30:39 volumio volumio[825]: ,establishing connection Sep 13 18:30:39 volumio volumio[825]: error: MPD error: The expression evaluated to a falsy value: Sep 13 18:30:39 volumio volumio[825]: assert.ok(self.idling) Sep 13 18:30:39 volumio volumio[825]: error: The expression evaluated to a falsy value: Sep 13 18:30:39 volumio volumio[825]: assert.ok(self.idling) Sep 13 18:30:39 volumio volumio[825]: error: MPD error: The expression evaluated to a falsy value: Sep 13 18:30:39 volumio volumio[825]: assert.ok(self.idling) Sep 13 18:30:39 volumio volumio[825]: error: The expression evaluated to a falsy value: Sep 13 18:30:39 volumio volumio[825]: assert.ok(self.idling) Sep 13 18:30:39 volumio volumio[825]: info: touch_display: X display number found: 0 Sep 13 18:30:39 volumio volumio[825]: error: updateQueue error: null Sep 13 18:30:40 volumio volumio[825]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 13 18:30:44 volumio ntpd[630]: Soliciting pool server 194.177.34.116 Sep 13 18:30:44 volumio ntpd[630]: Soliciting pool server 129.250.35.251 Sep 13 18:30:44 volumio volumio[825]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 13 18:30:44 volumio ntpd[630]: Soliciting pool server 172.232.44.156 Sep 13 18:30:44 volumio ntpd[630]: Soliciting pool server 185.254.100.25 Sep 13 18:30:45 volumio ntpd[630]: Soliciting pool server 109.190.177.200 Sep 13 18:30:45 volumio ntpd[630]: Soliciting pool server 62.210.129.125 Sep 13 18:30:46 volumio volumio[825]: Cannot compose Albumart path Sep 13 18:30:46 volumio ntpd[630]: Soliciting pool server 5.42.158.119 Sep 13 18:30:46 volumio ntpd[630]: Soliciting pool server 37.59.63.125 Sep 13 18:30:47 volumio ntpd[630]: Soliciting pool server 217.154.21.219 Sep 13 18:30:47 volumio ntpd[630]: Soliciting pool server 172.234.184.36 Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::volumioGetBrowseSources Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::volumioGetBrowseSources Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::volumioGetBrowseSources Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:30:47 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 13 18:30:47 volumio volumio[825]: info: Adding plugin bluetooth to MyMusic Plugins Sep 13 18:30:47 volumio volumio[825]: info: Adding plugin multiroom to MyMusic Plugins Sep 13 18:30:47 volumio volumio[825]: info: Adding plugin metavolumio to MyMusic Plugins Sep 13 18:30:47 volumio volumio[825]: info: Adding plugin cd_controller to MyMusic Plugins Sep 13 18:30:47 volumio volumio[825]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 13 18:30:47 volumio volumio[825]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 13 18:30:47 volumio volumio[825]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 13 18:30:47 volumio ntpd[630]: Soliciting pool server 172.232.63.219 Sep 13 18:30:47 volumio ntpd[630]: Soliciting pool server 79.143.250.33 Sep 13 18:30:48 volumio ntpd[630]: Soliciting pool server 37.187.132.168 Sep 13 18:30:48 volumio ntpd[630]: Soliciting pool server 51.68.44.27 Sep 13 18:30:49 volumio volumio[825]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 13 18:30:49 volumio volumio[825]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 13 18:30:49 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:30:49 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:30:49 volumio volumio[825]: info: Starting MyVolumio Remote Streaming Endpoints Sep 13 18:30:49 volumio volumio[825]: info: MyVolumio login type: Token Sep 13 18:30:49 volumio volumio[825]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 13 18:30:49 volumio volumio[825]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 13 18:30:49 volumio ntpd[630]: Soliciting pool server 82.67.41.119 Sep 13 18:30:49 volumio ntpd[630]: Soliciting pool server 54.38.114.34 Sep 13 18:30:50 volumio volumio[825]: info: Starting Streaming Service Transparent Proxy Sep 13 18:30:50 volumio ntpd[630]: Soliciting pool server 2001:41d0:1004:2398:c3:123:123:123 Sep 13 18:30:51 volumio volumio[825]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 13 18:30:51 volumio volumio[825]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 13 18:30:51 volumio volumio[825]: info: Streaming services startup Sep 13 18:30:51 volumio volumio[825]: info: Starting Streaming Daemon Sep 13 18:30:51 volumio volumio[825]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 13 18:30:51 volumio sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 13 18:30:51 volumio sudo[1389]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:30:51 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:30:51 volumio sudo[1389]: pam_unix(sudo:session): session closed for user root Sep 13 18:30:51 volumio volumio[825]: error: Cannot start Volumio Streaming Daemon Sep 13 18:30:51 volumio volumio[825]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 13 18:30:51 volumio volumio[825]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 13 18:30:51 volumio volumio[1001]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 13 18:30:51 volumio volumio[825]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 13 18:31:08 volumio ntpd[630]: receive: Unexpected origin timestamp 0xec701abb.ffa332e3 does not match aorg 0000000000.00000000 from server@54.38.114.34 xmt 0xec701acc.e6153c7e Sep 13 18:31:08 volumio ntpd[630]: receive: Unexpected origin timestamp 0xec701abb.ffb55ea7 does not match aorg 0000000000.00000000 from server@62.210.129.125 xmt 0xec701acc.e4bb0b80 Sep 13 18:31:08 volumio ntpd[630]: receive: Unexpected origin timestamp 0xec701abb.ffb7089d does not match aorg 0000000000.00000000 from server@185.254.100.25 xmt 0xec701acc.e6ebe229 Sep 13 18:31:08 volumio ntpd[630]: receive: Unexpected origin timestamp 0xec701abb.ffaac0ff does not match aorg 0000000000.00000000 from server@51.68.44.27 xmt 0xec701acc.e6680132 Sep 13 18:31:08 volumio ntpd[630]: receive: Unexpected origin timestamp 0xec701abb.ffade792 does not match aorg 0000000000.00000000 from server@79.143.250.33 xmt 0xec701acc.e6861459 Sep 13 18:31:08 volumio ntpd[630]: receive: Unexpected origin timestamp 0xec701abb.ffb3c9b8 does not match aorg 0000000000.00000000 from server@109.190.177.200 xmt 0xec701acc.e71485cc Sep 13 18:31:08 volumio ntpd[630]: receive: Unexpected origin timestamp 0xec701abb.ffafd381 does not match aorg 0000000000.00000000 from server@217.154.21.219 xmt 0xec701acc.e6d37586 Sep 13 18:31:08 volumio ntpd[630]: receive: Unexpected origin timestamp 0xec701abb.ffb2a8cc does not match aorg 0000000000.00000000 from server@5.42.158.119 xmt 0xec701acc.e77a0d2a Sep 13 18:31:09 volumio volumio[825]: STREAMING PROXY: Starting server on port 3245 Sep 13 18:31:09 volumio volumio[825]: Node JS runtime: 14 Sep 13 18:31:09 volumio volumio[825]: info: MyVolumio token set successfully Sep 13 18:31:09 volumio volumio[825]: info: MYVOLUMIO: Adding device Sep 13 18:31:09 volumio volumio[825]: info: MYVOLUMIO: Evaluating Server Sep 13 18:31:10 volumio volumio[825]: info: MyVolumio status changed Sep 13 18:31:10 volumio volumio[825]: info: Streaming services startup Sep 13 18:31:10 volumio volumio[825]: info: Starting Streaming Daemon Sep 13 18:31:10 volumio volumio[825]: info: Removing browser output: myVolumio user plan is not superstar Sep 13 18:31:10 volumio volumio[825]: info: Removing audio output: Sep 13 18:31:10 volumio volumio[825]: info: Stoppping Tunnel 1 Sep 13 18:31:10 volumio sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 13 18:31:10 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:10 volumio sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 13 18:31:10 volumio sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:10 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:10 volumio volumio[825]: error: Cannot start Volumio Streaming Daemon Sep 13 18:31:10 volumio volumio[825]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 13 18:31:10 volumio volumio[825]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 13 18:31:10 volumio sudo[1449]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:10 volumio volumio[825]: info: Remote SSH Stopped Sep 13 18:31:12 volumio volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 10 Sep 13 18:31:12 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:13 volumio volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 11 Sep 13 18:31:15 volumio volumio[825]: info: Setting Geolocation for MyVolumio to eu6 Sep 13 18:31:15 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:31:15 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:31:15 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:31:15 volumio volumio[825]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 12 Sep 13 18:31:15 volumio volumio[825]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 13 18:31:15 volumio volumio[825]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Sep 13 18:31:15 volumio sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Cecile Sep 13 18:31:16 volumio sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:16 volumio sudo[1453]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:16 volumio volumio[825]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 18:31:16 volumio volumio[825]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/Cecile' Sep 13 18:31:16 volumio volumio[825]: at Object.rmdirSync (fs.js:890:10) Sep 13 18:31:16 volumio volumio[825]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Sep 13 18:31:16 volumio volumio[825]: at ChildProcess.exithandler (child_process.js:299:7) Sep 13 18:31:16 volumio volumio[825]: at ChildProcess.emit (events.js:315:20) Sep 13 18:31:16 volumio volumio[825]: at maybeClose (internal/child_process.js:1048:16) Sep 13 18:31:16 volumio volumio[825]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Sep 13 18:31:16 volumio volumio[825]: errno: -16, Sep 13 18:31:16 volumio volumio[825]: syscall: 'rmdir', Sep 13 18:31:16 volumio volumio[825]: code: 'EBUSY', Sep 13 18:31:16 volumio volumio[825]: path: '/mnt/NAS/Cecile' Sep 13 18:31:16 volumio volumio[825]: } Sep 13 18:31:16 volumio volumio[825]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 18:31:16 volumio sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-13 18:30 Sep 13 18:31:16 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:16 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:17 volumio volumio-remote-updater[521]: [2025-09-13 18:31:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 13 18:31:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 13 18:31:17 volumio volumio-remote-updater[521]: [2025-09-13 18:31:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 13 18:31:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 13 18:31:17 volumio systemd[1]: Started dynamicswap service. Sep 13 18:31:17 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 13 18:31:17 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 13 18:31:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 13 18:31:17 volumio systemd[1]: Stopped Volumio Backend Module. Sep 13 18:31:17 volumio systemd[1]: Started Volumio Backend Module. Sep 13 18:31:17 volumio systemd[1]: Started dynamicswap service. Sep 13 18:31:17 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 13 18:31:20 volumio volumio[1478]: info: ------------------------------------------- Sep 13 18:31:20 volumio volumio[1478]: info: ----- Volumio3 ---- Sep 13 18:31:20 volumio volumio[1478]: info: ------------------------------------------- Sep 13 18:31:20 volumio volumio[1478]: info: ----- System startup ---- Sep 13 18:31:20 volumio volumio[1478]: info: ------------------------------------------- Sep 13 18:31:21 volumio volumio[1478]: info: MYVOLUMIO Environment detected Sep 13 18:31:21 volumio volumio[1478]: info: Plugin folders cleanup Sep 13 18:31:21 volumio volumio[1478]: info: Scanning into folder /volumio/app/plugins/ Sep 13 18:31:21 volumio volumio[1478]: info: Scanning category audio_interface Sep 13 18:31:21 volumio volumio[1478]: info: Scanning category miscellanea Sep 13 18:31:21 volumio volumio[1478]: info: Scanning category music_service Sep 13 18:31:21 volumio volumio[1478]: info: Scanning category plugins.json Sep 13 18:31:21 volumio volumio[1478]: info: Scanning category system_controller Sep 13 18:31:21 volumio volumio[1478]: info: Scanning category user_interface Sep 13 18:31:21 volumio volumio[1478]: info: Scanning into folder /data/plugins/ Sep 13 18:31:21 volumio volumio[1478]: info: Scanning category system_hardware Sep 13 18:31:21 volumio volumio[1478]: info: Scanning category user_interface Sep 13 18:31:21 volumio volumio[1478]: info: Plugin folders cleanup completed Sep 13 18:31:21 volumio volumio[1478]: info: ------------------------------------------- Sep 13 18:31:21 volumio volumio[1478]: info: ----- Core plugins startup ---- Sep 13 18:31:21 volumio volumio[1478]: info: ------------------------------------------- Sep 13 18:31:21 volumio volumio[1478]: info: Loading plugins from folder /volumio/app/plugins/ Sep 13 18:31:21 volumio volumio[1478]: info: Adding plugin upnp to MyMusic Plugins Sep 13 18:31:21 volumio volumio[1478]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 13 18:31:21 volumio volumio[1478]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 13 18:31:21 volumio volumio[1478]: info: Loading plugins from folder /data/plugins/ Sep 13 18:31:21 volumio volumio[1478]: info: Loading plugin "system"... Sep 13 18:31:21 volumio volumio[1478]: info: Loading plugin "appearance"... Sep 13 18:31:22 volumio volumio-remote-updater[521]: [2025-09-13 18:31:22] [connect] Successful connection Sep 13 18:31:23 volumio volumio[1478]: info: Loading plugin "network"... Sep 13 18:31:23 volumio volumio[1478]: info: Refreshing Cached IP Addresses Sep 13 18:31:23 volumio sudo[1505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 13 18:31:23 volumio sudo[1505]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:23 volumio volumio[1478]: info: Loading plugin "services"... Sep 13 18:31:23 volumio volumio[1478]: info: Loading plugin "alsa_controller"... Sep 13 18:31:23 volumio sudo[1505]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:23 volumio sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 13 18:31:23 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:23 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 13 18:31:23 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:23 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:23 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 13 18:31:23 volumio volumio[1478]: info: Loading plugin "wizard"... Sep 13 18:31:23 volumio volumio[1478]: info: Loading plugin "networkfs"... Sep 13 18:31:23 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 1 ,retrying in 5 seconds Sep 13 18:31:23 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 1 ,retrying in 5 seconds Sep 13 18:31:23 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 1 ,retrying in 5 seconds Sep 13 18:31:23 volumio volumio[1478]: info: Starting Udev Watcher for removable devices Sep 13 18:31:23 volumio volumio[1478]: info: Ignoring mount for partition: boot Sep 13 18:31:23 volumio volumio[1478]: info: Ignoring mount for partition: volumio Sep 13 18:31:23 volumio volumio[1478]: info: Ignoring mount for partition: volumio_data Sep 13 18:31:23 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 13 18:31:23 volumio volumio[1478]: info: Loading plugin "volumio_command_line_client"... Sep 13 18:31:23 volumio volumio[1478]: info: Loading plugin "upnp"... Sep 13 18:31:23 volumio volumio[1478]: info: [1757781083655] Starting Upmpd Daemon Sep 13 18:31:23 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 13 18:31:23 volumio volumio[1478]: info: Loading plugin "my_music"... Sep 13 18:31:23 volumio volumio[1478]: info: Loading plugin "mpd"... Sep 13 18:31:24 volumio volumio[1478]: info: Loading plugin "upnp_browser"... Sep 13 18:31:24 volumio volumio[1478]: info: Loading plugin "alarm-clock"... Sep 13 18:31:24 volumio volumio[1478]: info: Loading plugin "airplay_emulation"... Sep 13 18:31:24 volumio volumio[1478]: info: Starting Shairport Sync Sep 13 18:31:24 volumio volumio[1478]: info: Loading plugin "last_100"... Sep 13 18:31:24 volumio volumio[1478]: info: Loading plugin "webradio"... Sep 13 18:31:25 volumio volumio[1478]: info: Loading plugin "i2s_dacs"... Sep 13 18:31:25 volumio volumio[1478]: info: I2S DAC not set, start Auto-detection Sep 13 18:31:25 volumio volumio[1478]: info: Loading plugin "volumiodiscovery"... Sep 13 18:31:25 volumio volumio[1478]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 13 18:31:25 volumio volumio[1478]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 13 18:31:25 volumio volumio[1478]: *** WARNING *** For more information see Sep 13 18:31:25 volumio volumio[1478]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 13 18:31:25 volumio volumio[1478]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 13 18:31:25 volumio volumio[1478]: *** WARNING *** For more information see Sep 13 18:31:25 volumio node[1478]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 13 18:31:25 volumio node[1478]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 13 18:31:25 volumio node[1478]: *** WARNING *** For more information see Sep 13 18:31:25 volumio node[1478]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 13 18:31:25 volumio node[1478]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 13 18:31:25 volumio node[1478]: *** WARNING *** For more information see Sep 13 18:31:25 volumio volumio[1478]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 13 18:31:25 volumio volumio[1478]: info: Discovery: Started advertising with name: Volumio Sep 13 18:31:25 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 13 18:31:25 volumio volumio[1478]: info: Loading plugin "gpio-buttons"... Sep 13 18:31:26 volumio volumio[1478]: info: GPIO-Buttons initialized Sep 13 18:31:26 volumio volumio[1478]: info: Loading plugin "outputs"... Sep 13 18:31:26 volumio volumio[1478]: info: Loading plugin "albumart"... Sep 13 18:31:26 volumio volumio[1478]: info: Plugin example_plugin is not enabled Sep 13 18:31:26 volumio volumio[1478]: info: Loading plugin "inputs"... Sep 13 18:31:26 volumio volumio[1478]: info: Loading plugin "updater_comm"... Sep 13 18:31:26 volumio volumio[1478]: info: Plugin mpdemulation is not enabled Sep 13 18:31:26 volumio volumio[1478]: info: Loading plugin "rest_api"... Sep 13 18:31:26 volumio volumio[1478]: info: Loading plugin "websocket"... Sep 13 18:31:26 volumio volumio[1478]: info: Loading plugin "gpio_control"... Sep 13 18:31:26 volumio volumio[1478]: Forking 3 albumart workers Sep 13 18:31:26 volumio volumio[1478]: gpiox_arm32 V2.1.0 Sep 13 18:31:27 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:27 volumio volumio[1001]: ............................................................................................................................................................++++ Sep 13 18:31:28 volumio volumio[1478]: info: Applying required configuration parameters for plugin gpio_control Sep 13 18:31:28 volumio volumio[1478]: info: Loading plugin "touch_display"... Sep 13 18:31:28 volumio volumio[1478]: Starting albumart workers Sep 13 18:31:28 volumio volumio[1478]: Starting albumart workers Sep 13 18:31:28 volumio volumio[1478]: Starting albumart workers Sep 13 18:31:28 volumio volumio[1478]: info: Applying required configuration parameters for plugin touch_display Sep 13 18:31:29 volumio volumio[1478]: info: Loading i18n strings for locale fr Sep 13 18:31:29 volumio volumio[1478]: Updating browse sources language Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::initPlayerControls Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: Express server listening on port 3000 Sep 13 18:31:29 volumio volumio[1478]: [Metrics] WebUI: 10s 272.44ms Sep 13 18:31:29 volumio volumio[1478]: info: CoreStateMachine::resetVolumioState Sep 13 18:31:29 volumio volumio[1478]: info: CoreStateMachine::getcurrentVolume Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::volumioRetrievevolume Sep 13 18:31:29 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 18:31:29 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 2 ,retrying in 5 seconds Sep 13 18:31:29 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 2 ,retrying in 5 seconds Sep 13 18:31:29 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 2 ,retrying in 5 seconds Sep 13 18:31:30 volumio volumio[1478]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 1 Sep 13 18:31:30 volumio volumio[1478]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:142.0) Gecko/20100101 Firefox/142.0 Total Clients: 2 Sep 13 18:31:30 volumio volumio[1478]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 3 Sep 13 18:31:30 volumio volumio[1478]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:142.0) Gecko/20100101 Firefox/142.0 Total Clients: 4 Sep 13 18:31:30 volumio volumio[1478]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 5 Sep 13 18:31:30 volumio volumio[1478]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Sep 13 18:31:30 volumio volumio[1478]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Sep 13 18:31:30 volumio volumio[1478]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Sep 13 18:31:30 volumio volumio[1478]: info: Reloading queue from file Sep 13 18:31:30 volumio volumio[1478]: info: VolumeController:: Volume=100 Mute =false Sep 13 18:31:30 volumio volumio[1478]: info: CoreStateMachine::pushState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Sep 13 18:31:30 volumio volumio[1478]: info: CoreStateMachine::updateTrackBlock Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrackBlock Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioRetrievevolume Sep 13 18:31:30 volumio volumio[1478]: info: Setting Device type: Raspberry PI Sep 13 18:31:30 volumio volumio[1478]: info: CoreStateMachine::setRepeat null single undefined Sep 13 18:31:30 volumio volumio[1478]: info: CoreStateMachine::pushState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Sep 13 18:31:30 volumio volumio[1478]: info: CoreStateMachine::setRandom null Sep 13 18:31:30 volumio volumio[1478]: info: CoreStateMachine::pushState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 18:31:30 volumio volumio[1478]: info: Received Get System Info Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 18:31:30 volumio volumio[1478]: info: Discovery: Getting this device information Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: Listing playlists Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 13 18:31:30 volumio volumio[1478]: info: Received Get System Info Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 18:31:30 volumio volumio[1478]: info: Discovery: Getting this device information Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 18:31:30 volumio volumio[1478]: info: Received Get System Info Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 18:31:30 volumio volumio[1478]: info: Discovery: Getting this device information Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: Listing playlists Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 13 18:31:30 volumio volumio[1478]: info: Received Get System Info Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 18:31:30 volumio volumio[1478]: info: Discovery: Getting this device information Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 18:31:30 volumio volumio[1478]: info: Received Get System Info Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 18:31:30 volumio volumio[1478]: info: Discovery: Getting this device information Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: Listing playlists Sep 13 18:31:30 volumio volumio[1478]: info: VolumeController:: Volume=100 Mute =false Sep 13 18:31:30 volumio volumio[1478]: info: CoreStateMachine::pushState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioPushState Sep 13 18:31:30 volumio volumio[1478]: info: Discovery: adding 0d3fc09b-52b0-42fd-b197-1192af519df0 Sep 13 18:31:30 volumio volumio[1478]: info: Discovery: Found device Volumio Sep 13 18:31:30 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:30 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:31 volumio volumio[1478]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.50 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:142.0) Gecko/20100101 Firefox/142.0 Total Clients: 9 Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:31 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:31 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 18:31:31 volumio volumio[1478]: info: Received Get System Info Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 18:31:31 volumio volumio[1478]: info: Discovery: Getting this device information Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:31 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 18:31:31 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:31 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:31 volumio volumio[1478]: info: Listing playlists Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 13 18:31:32 volumio volumio[1478]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 13 18:31:32 volumio volumio[1478]: info: Received Get System Version Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 13 18:31:32 volumio volumio[1478]: info: Received Get System Info Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 18:31:32 volumio volumio[1478]: info: Discovery: Getting this device information Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::volumioGetState Sep 13 18:31:32 volumio volumio[1478]: info: CorePlayQueue::getTrack 0 Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:32 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 13 18:31:32 volumio volumio[1001]: ............................................++++ Sep 13 18:31:32 volumio volumio[1001]: e is 65537 (0x010001) Sep 13 18:31:32 volumio volumio[1001]: writing RSA key Sep 13 18:31:33 volumio sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 13 18:31:33 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:33 volumio sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 13 18:31:33 volumio sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:33 volumio sudo[1620]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:33 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:33 volumio sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 13 18:31:33 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 18:31:33 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root Sep 13 18:31:33 volumio volumio[1478]: info: Upmpdcli Daemon Started Sep 13 18:31:34 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 3 ,retrying in 5 seconds Sep 13 18:31:34 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 3 ,retrying in 5 seconds Sep 13 18:31:34 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 3 ,retrying in 5 seconds Sep 13 18:31:35 volumio volumio-remote-updater[521]: [2025-09-13 18:31:35] [connect] Successful connection Sep 13 18:31:35 volumio volumio-remote-updater[521]: [2025-09-13 18:31:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757781095 101 Sep 13 18:31:35 volumio volumio[1478]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 10 Sep 13 18:31:36 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:39 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 4 ,retrying in 5 seconds Sep 13 18:31:39 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 4 ,retrying in 5 seconds Sep 13 18:31:39 volumio volumio[1478]: info: Cannot mount NAS Cecile at system boot, trial number 4 ,retrying in 5 seconds Sep 13 18:31:40 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:44 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:44 volumio volumio[1478]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 13 18:31:44 volumio volumio[1478]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 13 18:31:44 volumio volumio[1478]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 13 18:31:48 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:52 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 18:31:52 volumio volumio[1478]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 13 18:31:52 volumio volumio[1478]: verbose: ControllerMpd::sendMpdCommand update Sep 13 18:31:52 volumio volumio[1478]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 18:31:52 volumio volumio[1478]: TypeError: Cannot read property 'then' of undefined Sep 13 18:31:52 volumio volumio[1478]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Sep 13 18:31:52 volumio volumio[1478]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) Sep 13 18:31:52 volumio volumio[1478]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1077:32) Sep 13 18:31:52 volumio volumio[1478]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1049:26) Sep 13 18:31:52 volumio volumio[1478]: at Socket.emit (events.js:315:20) Sep 13 18:31:52 volumio volumio[1478]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Sep 13 18:31:52 volumio volumio[1478]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 13 18:31:52 volumio volumio[1478]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 18:31:52 volumio sudo[1812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-13 18:30 Sep 13 18:31:52 volumio sudo[1812]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"