-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Wed 2025-05-28 14:43:23 EEST. -- May 28 14:31:50 volumio systemd-timedated[1343]: Changed local time to Wed May 28 14:31:50 2025 May 28 14:31:50 volumio systemd[1]: Starting Daily apt download activities... May 28 14:31:50 volumio sudo[1341]: pam_unix(sudo:session): session closed for user root May 28 14:31:50 volumio volumio-time-update[792]: volumio-time-update-util: System time updated successfully. May 28 14:31:50 volumio volumio[1149]: info: Discovery: A device disappeared from network May 28 14:31:50 volumio volumio[1149]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined May 28 14:31:50 volumio systemd[1]: Started Volumio Time Update Utility. May 28 14:31:50 volumio volumio[1149]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 May 28 14:31:50 volumio systemd[1]: apt-daily.service: Succeeded. May 28 14:31:50 volumio systemd[1]: Started Daily apt download activities. May 28 14:31:50 volumio systemd[1]: Starting Daily apt upgrade and clean activities... May 28 14:31:50 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. May 28 14:31:50 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 28 14:31:50 volumio nmbd[905]: [2025/05/28 14:31:50.668774, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 28 14:31:50 volumio nmbd[905]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 28 14:31:50 volumio systemd[1]: Started Samba NMB Daemon. May 28 14:31:50 volumio systemd[1]: Starting Samba Winbind Daemon... May 28 14:31:50 volumio winbindd[1443]: [2025/05/28 14:31:50.799259, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 28 14:31:50 volumio winbindd[1443]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 28 14:31:50 volumio winbindd[1443]: [2025/05/28 14:31:50.823216, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 28 14:31:50 volumio systemd[1]: Started Samba Winbind Daemon. May 28 14:31:50 volumio winbindd[1443]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 28 14:31:50 volumio systemd[1]: Starting Samba SMB Daemon... May 28 14:31:51 volumio smbd[1448]: [2025/05/28 14:31:51.105326, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 28 14:31:51 volumio systemd[1]: Started Samba SMB Daemon. May 28 14:31:51 volumio smbd[1448]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 28 14:31:51 volumio systemd[1]: Reached target Multi-User System. May 28 14:31:51 volumio systemd[1]: Reached target Graphical Interface. May 28 14:31:51 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 28 14:31:51 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 28 14:31:51 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 28 14:31:51 volumio systemd[1]: Startup finished in 11.469s (kernel) + 25.218s (userspace) = 36.688s. May 28 14:31:51 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root May 28 14:31:52 volumio volumio-remote-updater[794]: [2025-05-28 14:31:52] [connect] Successful connection May 28 14:31:52 volumio volumio-remote-updater[794]: [2025-05-28 14:31:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748431912 101 May 28 14:31:52 volumio volumio[1149]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 28 14:31:54 volumio volumio[1149]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 28 14:31:57 volumio volumio[1149]: info: Adding plugin bluetooth to MyMusic Plugins May 28 14:31:57 volumio volumio[1149]: info: Adding plugin multiroom to MyMusic Plugins May 28 14:31:57 volumio volumio[1149]: info: Adding plugin metavolumio to MyMusic Plugins May 28 14:31:57 volumio volumio[1149]: info: Adding plugin cd_controller to MyMusic Plugins May 28 14:31:57 volumio volumio[1149]: info: Adding plugin qobuzconnect to MyMusic Plugins May 28 14:31:57 volumio volumio[1149]: info: Adding plugin smart_inputs to MyMusic Plugins May 28 14:31:57 volumio volumio[1149]: info: Adding plugin tidalconnect to MyMusic Plugins May 28 14:31:57 volumio volumio[1149]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 28 14:31:57 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 28 14:31:58 volumio volumio[1149]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 28 14:31:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 28 14:31:58 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:31:58 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:31:58 volumio volumio[1149]: info: Starting MyVolumio Remote Streaming Endpoints May 28 14:31:58 volumio volumio[1149]: info: MyVolumio not started May 28 14:31:58 volumio volumio[1149]: info: Initializing device activation check May 28 14:31:58 volumio volumio[1149]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 28 14:31:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 28 14:31:58 volumio volumio[1149]: info: Starting Streaming Service Transparent Proxy May 28 14:31:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 28 14:31:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 28 14:31:58 volumio volumio[1149]: info: Streaming services startup May 28 14:31:58 volumio volumio[1149]: info: Starting Streaming Daemon May 28 14:31:58 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 28 14:31:58 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:31:58 volumio volumio[1149]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 28 14:31:58 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root May 28 14:31:58 volumio sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 14:31:58 volumio sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:31:58 volumio sudo[1489]: pam_unix(sudo:session): session closed for user root May 28 14:31:58 volumio volumio[1149]: error: Cannot start Volumio Streaming Daemon May 28 14:31:58 volumio volumio[1149]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 28 14:31:58 volumio volumio[1149]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 28 14:31:58 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 14:31:58 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:31:58 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root May 28 14:31:58 volumio sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 28 14:31:58 volumio sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:31:58 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 28 14:31:58 volumio sudo[1495]: pam_unix(sudo:session): session closed for user root May 28 14:31:58 volumio volumio[1149]: info: Upmpdcli Daemon Started May 28 14:31:58 volumio volumio[1497]: Generating RSA private key, 4096 bit long modulus (2 primes) May 28 14:31:58 volumio volumio[1149]: STREAMING PROXY: Starting server on port 3245 May 28 14:31:58 volumio volumio[1149]: Node JS runtime: 14 May 28 14:31:59 volumio sh[696]: timed out May 28 14:31:59 volumio dhcpcd[745]: timed out May 28 14:31:59 volumio sh[696]: dhcpcd exited May 28 14:31:59 volumio dhcpcd[745]: dhcpcd exited May 28 14:31:59 volumio sh[696]: ifup: failed to bring up eth0 May 28 14:31:59 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 28 14:31:59 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 28 14:31:59 volumio volumio[1497]: ...................++++ May 28 14:32:00 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 28 14:32:03 volumio volumio[1497]: .................................................................................++++ May 28 14:32:03 volumio volumio[1497]: e is 65537 (0x010001) May 28 14:32:03 volumio volumio[1497]: writing RSA key May 28 14:32:13 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 14:32:13 volumio volumio[1149]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 28 14:32:13 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:13 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::volumioGetBrowseSources May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 14:32:18 volumio volumio[1149]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 28 14:32:18 volumio volumio[1149]: info: Completed starting MyVolumio Plugin May 28 14:32:18 volumio volumio[1149]: [Metrics] CommandRouter: 31s 122.32ms May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::volumiosetStartupVolume May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::Close All Modals sent May 28 14:32:18 volumio volumio[1149]: info: CoreCommandRouter::Close All Modals sent May 28 14:32:18 volumio volumio[1149]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 28 14:32:18 volumio volumio[1149]: aplay: main:828: audio open error: Unknown error 524 May 28 14:32:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 14:32:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:32:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 14:32:19 volumio volumio-remote-updater[794]: No test mode May 28 14:32:19 volumio volumio-remote-updater[794]: No alpha test mode May 28 14:32:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 28 14:32:19 volumio volumio[1149]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 28 14:32:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 28 14:32:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 28 14:32:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 14:32:19 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 28 14:32:20 volumio systemd[1]: systemd-timedated.service: Succeeded. May 28 14:32:23 volumio sudo[1573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 14:32:23 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:32:23 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root May 28 14:32:23 volumio sudo[1575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 14:32:23 volumio sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:32:23 volumio sudo[1575]: pam_unix(sudo:session): session closed for user root May 28 14:32:23 volumio volumio[1149]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 4 May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::volumioGetVisibleSources May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:23 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 14:32:23 volumio volumio[1149]: info: Received Get System Info May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:32:23 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:23 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:23 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:23 volumio volumio[1149]: info: Listing playlists May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 14:32:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 14:32:24 volumio sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 14:32:24 volumio sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:32:24 volumio sudo[1579]: pam_unix(sudo:session): session closed for user root May 28 14:32:24 volumio sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 14:32:24 volumio sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:32:24 volumio sudo[1581]: pam_unix(sudo:session): session closed for user root May 28 14:32:24 volumio volumio[1149]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 4 May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::volumioGetVisibleSources May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:24 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 14:32:24 volumio volumio[1149]: info: Received Get System Info May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:32:24 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:24 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:24 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:24 volumio volumio[1149]: info: Listing playlists May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 14:32:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 14:32:25 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:25 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:25 volumio volumio[1149]: info: BOOT COMPLETED May 28 14:32:26 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 28 14:32:26 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:32:26 volumio volumio[1149]: info: Received Get System Info May 28 14:32:26 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:32:26 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:32:26 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:32:26 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:26 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:26 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:32:34 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 14:32:36 volumio ntpd[956]: Soliciting pool server 193.106.144.6 May 28 14:32:37 volumio ntpd[956]: Soliciting pool server 91.236.251.34 May 28 14:32:37 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse May 28 14:32:38 volumio ntpd[956]: Soliciting pool server 37.53.87.250 May 28 14:32:38 volumio ntpd[956]: Soliciting pool server 62.205.159.153 May 28 14:32:38 volumio ntpd[956]: Soliciting pool server 46.173.175.211 May 28 14:32:39 volumio ntpd[956]: Soliciting pool server 37.53.92.252 May 28 14:32:39 volumio ntpd[956]: Soliciting pool server 62.149.0.30 May 28 14:32:39 volumio ntpd[956]: Soliciting pool server 91.236.251.13 May 28 14:32:39 volumio ntpd[956]: Soliciting pool server 162.159.200.123 May 28 14:32:40 volumio ntpd[956]: Soliciting pool server 91.236.251.234 May 28 14:32:40 volumio ntpd[956]: Soliciting pool server 31.28.161.68 May 28 14:32:41 volumio ntpd[956]: Soliciting pool server 62.149.2.7 May 28 14:32:41 volumio volumio[1149]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 14:32:41 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 28 14:32:41 volumio volumio[1149]: Disk list : [ { device: 'nvme0n1', name: 'NVMe', size: '119.2G' } ] May 28 14:32:41 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 28 14:32:41 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 28 14:32:41 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 28 14:32:41 volumio volumio[1149]: info: Received Get System Version May 28 14:32:41 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 14:32:42 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 14:32:42 volumio volumio[1149]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 28 14:32:42 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 28 14:32:42 volumio volumio[1149]: info: Received Get System Version May 28 14:32:42 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 14:32:42 volumio volumio[1149]: info: Received Get System Info May 28 14:32:42 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:32:42 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:32:42 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:32:42 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:32:42 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:32:42 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:32:47 volumio ntpd[956]: receive: Unexpected origin timestamp 0xebe172df.0223f6e7 does not match aorg 0000000000.00000000 from server@46.173.175.211 xmt 0xebe172df.f83b0c45 May 28 14:32:47 volumio ntpd[956]: receive: Unexpected origin timestamp 0xebe172df.02251ce5 does not match aorg 0000000000.00000000 from server@37.53.87.250 xmt 0xebe172df.f7dbdb7f May 28 14:32:49 volumio volumio-remote-updater[794]: No test mode May 28 14:32:49 volumio volumio-remote-updater[794]: No alpha test mode May 28 14:32:49 volumio volumio[1149]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 28 14:32:49 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 28 14:32:51 volumio volumio[1149]: info: CoreCommandRouter::Close All Modals sent May 28 14:34:41 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:34:41 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:34:43 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:34:48 volumio sudo[1933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 14:34:48 volumio sudo[1933]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:34:48 volumio sudo[1933]: pam_unix(sudo:session): session closed for user root May 28 14:34:48 volumio sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 14:34:48 volumio sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:34:48 volumio sudo[1935]: pam_unix(sudo:session): session closed for user root May 28 14:34:48 volumio volumio[1149]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 5 May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:34:48 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:34:48 volumio volumio[1149]: info: Received Get System Info May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:34:48 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:34:48 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:34:48 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::volumioGetVisibleSources May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:34:48 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:34:48 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 14:34:48 volumio volumio[1149]: info: Received Get System Info May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:34:48 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:34:48 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:34:48 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:34:48 volumio volumio[1149]: info: Listing playlists May 28 14:34:48 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 14:34:58 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 14:35:00 volumio volumio[1149]: info: CoreCommandRouter::Close All Modals sent May 28 14:35:00 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted May 28 14:35:28 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:35:30 volumio volumio[1149]: info: Received Get System Info May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:35:30 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:30 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:35:30 volumio volumio[1149]: info: Received Get System Info May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:35:30 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:30 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:30 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:35:31 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 28 14:35:31 volumio volumio[1149]: info: MyVolumio login type: Token May 28 14:35:31 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 28 14:35:31 volumio volumio[1149]: info: MyVolumio login type: Token May 28 14:35:31 volumio volumio[1149]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 28 14:35:32 volumio volumio[1149]: info: MyVolumio token set successfully May 28 14:35:32 volumio volumio[1149]: info: MYVOLUMIO: Adding device May 28 14:35:32 volumio volumio[1149]: info: MYVOLUMIO: Evaluating Server May 28 14:35:32 volumio volumio[1149]: info: MyVolumio status changed May 28 14:35:32 volumio volumio[1149]: info: Streaming services startup May 28 14:35:32 volumio volumio[1149]: info: Starting Streaming Daemon May 28 14:35:32 volumio volumio[1149]: info: Removing browser output: myVolumio user plan is not superstar May 28 14:35:32 volumio volumio[1149]: info: Removing audio output: May 28 14:35:32 volumio volumio[1149]: info: Stoppping Tunnel 1 May 28 14:35:32 volumio sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 28 14:35:32 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:35:32 volumio sudo[2081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 28 14:35:32 volumio sudo[2081]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:35:32 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root May 28 14:35:32 volumio volumio[1149]: error: Cannot start Volumio Streaming Daemon May 28 14:35:32 volumio volumio[1149]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 28 14:35:32 volumio volumio[1149]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 28 14:35:32 volumio sudo[2081]: pam_unix(sudo:session): session closed for user root May 28 14:35:32 volumio volumio[1149]: info: Remote SSH Stopped May 28 14:35:32 volumio volumio[1149]: info: Setting Geolocation for MyVolumio to eu10 May 28 14:35:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:32 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:33 volumio volumio[1149]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 28 14:35:33 volumio volumio[1149]: info: Updating MyVolumio device info May 28 14:35:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:34 volumio volumio[1149]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 28 14:35:37 volumio volumio[1149]: info: MYVOLUMIO: Adding device May 28 14:35:37 volumio volumio[1149]: info: MYVOLUMIO: Evaluating Server May 28 14:35:37 volumio volumio[1149]: info: Setting Geolocation for MyVolumio to eu4 May 28 14:35:37 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:37 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:37 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:38 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:35:38 volumio volumio[1149]: info: Received Get System Info May 28 14:35:38 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:35:38 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:35:38 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:35:38 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:38 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:38 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:35:38 volumio volumio[1149]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 28 14:35:38 volumio volumio[1149]: info: Updating MyVolumio device info May 28 14:35:38 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:38 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:38 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 14:35:39 volumio volumio[1149]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 28 14:35:44 volumio sudo[2116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 14:35:44 volumio sudo[2116]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:35:44 volumio sudo[2116]: pam_unix(sudo:session): session closed for user root May 28 14:35:44 volumio sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 14:35:44 volumio sudo[2118]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:35:44 volumio sudo[2118]: pam_unix(sudo:session): session closed for user root May 28 14:35:44 volumio volumio[1149]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 5 May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:45 volumio volumio[1149]: info: Received Get System Info May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:35:45 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetVisibleSources May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 14:35:45 volumio volumio[1149]: info: Received Get System Info May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:35:45 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:45 volumio volumio[1149]: info: Listing playlists May 28 14:35:45 volumio volumio[1149]: info: Received Get System Info May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:35:45 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 14:35:45 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:35:45 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:35:47 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:35:47 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 28 14:35:54 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 14:36:39 volumio volumio[1149]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/minidlna/volumio/buster/armhf May 28 14:36:40 volumio volumio[1149]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/minidlna/volumio/buster/armhf May 28 14:36:40 volumio volumio[1149]: info: Folder /tmp/plugins removed May 28 14:36:40 volumio volumio[1149]: info: Check plugin dependencies May 28 14:36:40 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 14:36:40 volumio volumio[1149]: info: Checking if plugin already exists May 28 14:36:40 volumio volumio[1149]: info: Rename folder May 28 14:36:40 volumio volumio[1149]: info: Folder /tmp/downloaded_plugin.zip removed May 28 14:36:40 volumio volumio[1149]: info: Move to category May 28 14:36:40 volumio volumio[1149]: info: Checking if install.sh is present May 28 14:36:40 volumio volumio[1149]: info: Executing install.sh May 28 14:36:41 volumio sudo[2271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/minidlna/install.sh May 28 14:36:41 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:36:41 volumio volumio[1149]: info: Completing "UIConfig.json" May 28 14:36:41 volumio volumio[1149]: info: Installing MiniDLNA May 28 14:36:41 volumio volumio[1149]: info: Get:1 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB] May 28 14:36:41 volumio volumio[1149]: info: Get:2 https://deb.nodesource.com/node_14.x buster InRelease [4584 B] May 28 14:36:41 volumio volumio[1149]: info: Get:3 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] May 28 14:36:41 volumio volumio[1149]: info: Get:4 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB] May 28 14:36:41 volumio volumio[1149]: info: Get:5 https://deb.nodesource.com/node_14.x buster/main armhf Packages [786 B] May 28 14:36:41 volumio volumio[1149]: info: Get:6 http://raspbian.raspberrypi.org/raspbian buster/main armhf Packages [13.0 MB] May 28 14:36:46 volumio volumio[1149]: info: Get:7 http://raspbian.raspberrypi.org/raspbian buster/contrib armhf Packages [58.8 kB] May 28 14:36:46 volumio volumio[1149]: info: Get:8 http://raspbian.raspberrypi.org/raspbian buster/non-free armhf Packages [110 kB] May 28 14:36:46 volumio volumio[1149]: info: Get:9 http://raspbian.raspberrypi.org/raspbian buster/rpi armhf Packages [1360 B] May 28 14:36:48 volumio volumio[1149]: info: Fetched 13.7 MB in 7s (1910 kB/s) May 28 14:36:49 volumio volumio[1149]: info: Reading package lists... May 28 14:36:49 volumio volumio[1149]: info: Reading package lists... May 28 14:36:49 volumio volumio[1149]: info: Building dependency tree... May 28 14:36:49 volumio volumio[1149]: info: Reading state information... May 28 14:36:50 volumio volumio[1149]: info: The following additional packages will be installed: May 28 14:36:50 volumio volumio[1149]: info: libexif12 May 28 14:36:50 volumio volumio[1149]: info: The following NEW packages will be installed: May 28 14:36:50 volumio volumio[1149]: info: libexif12 minidlna May 28 14:36:50 volumio volumio[1149]: info: 0 upgraded, 2 newly installed, 0 to remove and 2 not upgraded. May 28 14:36:50 volumio volumio[1149]: info: Need to get 446 kB of archives. May 28 14:36:50 volumio volumio[1149]: info: After this operation, 2329 kB of additional disk space will be used. May 28 14:36:50 volumio volumio[1149]: info: Get:1 http://mirror.wheel.sk/raspbian/raspbian buster/main armhf libexif12 armhf 0.6.21-5.1+deb10u5 [313 kB] May 28 14:36:50 volumio volumio[1149]: info: Get:2 http://mirror.datacenter.by/pub/mirrors/archive.raspbian.org/raspbian buster/main armhf minidlna armhf 1.2.1+dfsg-2+deb10u4 [132 kB] May 28 14:36:51 volumio volumio[1149]: info: Fetched 446 kB in 1s (421 kB/s) May 28 14:36:51 volumio volumio[1149]: info: Selecting previously unselected package libexif12:armhf. May 28 14:36:52 volumio volumio[1149]: [620B blob data] May 28 14:36:52 volumio volumio[1149]: info: Preparing to unpack .../libexif12_0.6.21-5.1+deb10u5_armhf.deb ... May 28 14:36:52 volumio volumio[1149]: info: Unpacking libexif12:armhf (0.6.21-5.1+deb10u5) ... May 28 14:36:52 volumio volumio[1149]: info: Selecting previously unselected package minidlna. May 28 14:36:52 volumio volumio[1149]: info: Preparing to unpack .../minidlna_1.2.1+dfsg-2+deb10u4_armhf.deb ... May 28 14:36:52 volumio volumio[1149]: info: Unpacking minidlna (1.2.1+dfsg-2+deb10u4) ... May 28 14:36:59 volumio volumio[1149]: info: Setting up libexif12:armhf (0.6.21-5.1+deb10u5) ... May 28 14:36:59 volumio volumio[1149]: info: Setting up minidlna (1.2.1+dfsg-2+deb10u4) ... May 28 14:36:59 volumio groupadd[2550]: group added to /etc/group: name=minidlna, GID=123 May 28 14:36:59 volumio groupadd[2550]: group added to /etc/gshadow: name=minidlna May 28 14:36:59 volumio groupadd[2550]: new group: name=minidlna, GID=123 May 28 14:36:59 volumio useradd[2554]: new user: name=minidlna, UID=118, GID=123, home=/var/lib/minidlna, shell=/usr/sbin/nologin May 28 14:37:00 volumio usermod[2560]: change user 'minidlna' password May 28 14:37:00 volumio chage[2565]: changed password expiry for minidlna May 28 14:37:00 volumio chfn[2568]: changed user 'minidlna' information May 28 14:37:00 volumio systemd[1]: Reloading. May 28 14:37:00 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. May 28 14:37:00 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 28 14:37:00 volumio systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 28 14:37:00 volumio systemd[1]: Reloading. May 28 14:37:00 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. May 28 14:37:01 volumio systemd[1]: Reloading. May 28 14:37:01 volumio volumio[1149]: info: Created symlink /etc/systemd/system/multi-user.target.wants/minidlna.service → /lib/systemd/system/minidlna.service. May 28 14:37:01 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. May 28 14:37:01 volumio systemd[1]: Reloading. May 28 14:37:01 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. May 28 14:37:01 volumio systemd[1]: Starting MiniDLNA lightweight DLNA/UPnP-AV server... May 28 14:37:01 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. May 28 14:37:01 volumio volumio[1149]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... May 28 14:37:02 volumio volumio[1149]: info: Processing triggers for systemd (241-7~deb10u9+rpi1) ... May 28 14:37:02 volumio systemd[1]: Reloading. May 28 14:37:02 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. May 28 14:37:03 volumio systemd[1]: Reloading. May 28 14:37:03 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. May 28 14:37:03 volumio systemd[1]: Reloading. May 28 14:37:03 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. May 28 14:37:03 volumio systemd[1]: Reloading. May 28 14:37:03 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. May 28 14:37:03 volumio systemd[1]: Stopping MiniDLNA lightweight DLNA/UPnP-AV server... May 28 14:37:03 volumio systemd[1]: minidlna.service: Succeeded. May 28 14:37:03 volumio systemd[1]: Stopped MiniDLNA lightweight DLNA/UPnP-AV server. May 28 14:37:03 volumio volumio[1149]: info: Creating systemd unit /etc/systemd/system/minidlna.service May 28 14:37:03 volumio systemd[1]: Reloading. May 28 14:37:04 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. May 28 14:37:04 volumio volumio[1149]: info: Setting values for "network_interface" and "model_number" in /data/plugins/music_service/minidlna/config.json May 28 14:37:04 volumio volumio[1149]: info: Setting permissions to MiniDLNA folders May 28 14:37:04 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root May 28 14:37:04 volumio volumio[1149]: info: Install script completed May 28 14:37:04 volumio volumio[1149]: info: Adding reference to registry May 28 14:37:04 volumio volumio[1149]: info: Done installing plugin. May 28 14:37:04 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:37:04 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 28 14:37:04 volumio volumio[1149]: info: Folder /tmp/plugins removed May 28 14:37:04 volumio volumio[1149]: info: Folder /tmp/downloaded_plugin.zip removed May 28 14:37:04 volumio volumio[1149]: Plugin install end detected on script May 28 14:37:04 volumio volumio[1149]: info: Folder /data/temp removed May 28 14:37:06 volumio volumio[1149]: info: Enabling plugin minidlna May 28 14:37:06 volumio volumio[1149]: info: Loading plugin "minidlna"... May 28 14:37:06 volumio volumio[1149]: info: PLUGIN START: minidlna May 28 14:37:06 volumio volumio[1149]: info: Loading i18n strings for locale ru May 28 14:37:06 volumio volumio[1149]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. May 28 14:37:06 volumio volumio[1149]: info: minidlna: Found miniDLNA version 1.2.1 May 28 14:37:06 volumio volumio[1149]: info: minidlna: /data/minidlna.conf written May 28 14:37:06 volumio volumio[1149]: info: minidlna: Starting minidlna.service May 28 14:37:06 volumio sudo[2767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service May 28 14:37:06 volumio sudo[2767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:37:06 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. May 28 14:37:06 volumio sudo[2767]: pam_unix(sudo:session): session closed for user root May 28 14:37:06 volumio volumio[1149]: info: minidlna: systemctl start minidlna.service succeeded. May 28 14:37:06 volumio volumio[1149]: info: Done. May 28 14:37:52 volumio ntpd[956]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 28 14:38:16 volumio volumio[1149]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 28 14:38:18 volumio volumio[1149]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 28 14:38:18 volumio volumio[1149]: info: Folder /tmp/plugins removed May 28 14:38:18 volumio volumio[1149]: info: Check plugin dependencies May 28 14:38:18 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 14:38:18 volumio volumio[1149]: info: Checking if plugin already exists May 28 14:38:18 volumio volumio[1149]: info: Rename folder May 28 14:38:18 volumio volumio[1149]: info: Folder /tmp/downloaded_plugin.zip removed May 28 14:38:18 volumio volumio[1149]: info: Move to category May 28 14:38:23 volumio volumio[1149]: info: Checking if install.sh is present May 28 14:38:23 volumio volumio[1149]: info: Executing install.sh May 28 14:38:23 volumio sudo[2974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh May 28 14:38:23 volumio sudo[2974]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:38:23 volumio volumio[1149]: info: Installing Go-librespot May 28 14:38:23 volumio volumio[1149]: info: Checking old vollibrespot installs May 28 14:38:23 volumio systemd[1]: Reloading. May 28 14:38:23 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. May 28 14:38:23 volumio volumio[1149]: info: Downloading daemon May 28 14:38:25 volumio volumio[1149]: info: Creating directories May 28 14:38:25 volumio volumio[1149]: info: Creating data directory May 28 14:38:25 volumio volumio[1149]: info: Creating Start Script May 28 14:38:25 volumio systemd[1]: Reloading. May 28 14:38:25 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. May 28 14:38:25 volumio sudo[2974]: pam_unix(sudo:session): session closed for user root May 28 14:38:25 volumio volumio[1149]: Plugin install end detected on script May 28 14:38:25 volumio volumio[1149]: info: Install script completed May 28 14:38:25 volumio volumio[1149]: info: Adding reference to registry May 28 14:38:25 volumio volumio[1149]: info: Done installing plugin. May 28 14:38:25 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:38:25 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 28 14:38:25 volumio volumio[1149]: info: Folder /tmp/plugins removed May 28 14:38:25 volumio volumio[1149]: info: Folder /tmp/downloaded_plugin.zip removed May 28 14:38:25 volumio volumio[1149]: info: Folder /data/temp removed May 28 14:38:35 volumio volumio[1149]: info: Enabling plugin spop May 28 14:38:35 volumio volumio[1149]: info: Loading plugin "spop"... May 28 14:38:35 volumio volumio[1149]: info: PLUGIN START: spop May 28 14:38:35 volumio volumio[1149]: info: Creating Spotify config file May 28 14:38:35 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 14:38:35 volumio volumio[1149]: info: Done. May 28 14:38:35 volumio volumio[1149]: info: Spotify config file written May 28 14:38:35 volumio volumio[1149]: info: No need to fix Spotify hosts May 28 14:38:35 volumio sudo[3096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 28 14:38:35 volumio sudo[3096]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:38:35 volumio systemd[1]: Started go-librespot Daemon. May 28 14:38:35 volumio go-librespot[3098]: go-librespot daemon starting... May 28 14:38:35 volumio sudo[3096]: pam_unix(sudo:session): session closed for user root May 28 14:38:35 volumio go-librespot[3098]: time="2025-05-28T14:38:35+03:00" level=info msg="running go-librespot 0.2.0" May 28 14:38:35 volumio go-librespot[3098]: time="2025-05-28T14:38:35+03:00" level=debug msg="no app state found" May 28 14:38:35 volumio go-librespot[3098]: time="2025-05-28T14:38:35+03:00" level=debug msg="stored credentials not found" May 28 14:38:35 volumio go-librespot[3098]: time="2025-05-28T14:38:35+03:00" level=info msg="generated new device id: b529cbe9ee6f17ef0a245f73216507ff158cdf8f" May 28 14:38:35 volumio go-librespot[3098]: time="2025-05-28T14:38:35+03:00" level=info msg="api server listening on 127.0.0.1:9879" May 28 14:38:35 volumio go-librespot[3098]: time="2025-05-28T14:38:35+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 28 14:38:35 volumio go-librespot[3098]: time="2025-05-28T14:38:35+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 28 14:38:35 volumio go-librespot[3098]: time="2025-05-28T14:38:35+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 28 14:38:35 volumio go-librespot[3098]: time="2025-05-28T14:38:35+03:00" level=info msg="zeroconf server listening on port 45863" May 28 14:38:38 volumio volumio[1149]: info: go-librespot daemon successfully initialized May 28 14:38:41 volumio volumio[1149]: info: Initializing connection to go-librespot Websocket May 28 14:38:41 volumio go-librespot[3098]: time="2025-05-28T14:38:41+03:00" level=debug msg="new websocket client" May 28 14:38:41 volumio volumio[1149]: info: Connection to go-librespot Websocket established May 28 14:38:44 volumio volumio[1149]: info: Getting Spotify volume May 28 14:38:44 volumio volumio[1149]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 28 14:38:44 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:38:44 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:39:00 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 28 14:39:10 volumio volumio[1149]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/spop/4.1.4/volumio/buster/armhf May 28 14:39:11 volumio volumio[1149]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/spop/4.1.4/volumio/buster/armhf May 28 14:39:11 volumio volumio[1149]: info: Folder /tmp/plugins removed May 28 14:39:12 volumio volumio[1149]: info: Check plugin dependencies May 28 14:39:12 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 14:39:12 volumio volumio[1149]: info: Checking if plugin already exists May 28 14:39:12 volumio volumio[1149]: info: An error occurred installing the plugin. Rolling back config May 28 14:39:12 volumio volumio[1149]: info: Plugin folders cleanup May 28 14:39:12 volumio volumio[1149]: info: Scanning into folder /volumio/app/plugins/ May 28 14:39:12 volumio volumio[1149]: info: Scanning category audio_interface May 28 14:39:12 volumio volumio[1149]: info: Scanning category miscellanea May 28 14:39:12 volumio volumio[1149]: info: Scanning category music_service May 28 14:39:12 volumio volumio[1149]: info: Scanning category plugins.json May 28 14:39:12 volumio volumio[1149]: info: Scanning category system_controller May 28 14:39:12 volumio volumio[1149]: info: Scanning category user_interface May 28 14:39:12 volumio volumio[1149]: info: Scanning into folder /data/plugins/ May 28 14:39:12 volumio volumio[1149]: info: Scanning category music_service May 28 14:39:12 volumio volumio[1149]: info: Plugin folders cleanup completed May 28 14:39:12 volumio volumio[1149]: info: Error: Error May 28 14:39:12 volumio volumio[1149]: info: Folder /tmp/plugins removed May 28 14:39:12 volumio volumio[1149]: info: Folder /tmp/downloaded_plugin.zip removed May 28 14:39:12 volumio volumio[1149]: info: Folder /tmp/downloaded_plugin.zip removed May 28 14:39:12 volumio volumio[1149]: info: Folder /data/temp removed May 28 14:39:31 volumio volumio[1149]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/minidlna/volumio/buster/armhf May 28 14:39:33 volumio volumio[1149]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/minidlna/volumio/buster/armhf May 28 14:39:33 volumio volumio[1149]: info: minidlna: Stopping minidlna.service May 28 14:39:33 volumio sudo[3267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop minidlna.service May 28 14:39:33 volumio sudo[3267]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:39:33 volumio systemd[1]: Stopping MiniDLNA lightweight DLNA/UPnP-AV server... May 28 14:39:33 volumio systemd[1]: minidlna.service: Succeeded. May 28 14:39:33 volumio systemd[1]: Stopped MiniDLNA lightweight DLNA/UPnP-AV server. May 28 14:39:33 volumio sudo[3267]: pam_unix(sudo:session): session closed for user root May 28 14:39:33 volumio volumio[1149]: info: minidlna: systemctl stop minidlna.service succeeded. May 28 14:39:33 volumio volumio[1149]: info: Check plugin dependencies May 28 14:39:33 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 14:39:33 volumio volumio[1149]: info: Rename folder May 28 14:39:33 volumio volumio[1149]: info: Folder /tmp/downloaded_plugin.zip removed May 28 14:39:33 volumio volumio[1149]: info: Move to category May 28 14:39:33 volumio volumio[1149]: info: Checking if install.sh is present May 28 14:39:33 volumio volumio[1149]: info: Executing install.sh May 28 14:39:33 volumio sudo[3281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/minidlna/install.sh May 28 14:39:33 volumio sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:39:33 volumio volumio[1149]: info: Completing "UIConfig.json" May 28 14:39:33 volumio volumio[1149]: info: Installing MiniDLNA May 28 14:39:33 volumio volumio[1149]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease May 28 14:39:33 volumio volumio[1149]: info: Hit:2 http://archive.raspberrypi.org/debian buster InRelease May 28 14:39:34 volumio volumio[1149]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease May 28 14:39:35 volumio volumio[1149]: info: Reading package lists... May 28 14:39:35 volumio volumio[1149]: info: Reading package lists... May 28 14:39:35 volumio volumio[1149]: info: Building dependency tree... May 28 14:39:35 volumio volumio[1149]: info: Reading state information... May 28 14:39:36 volumio volumio[1149]: info: minidlna is already the newest version (1.2.1+dfsg-2+deb10u4). May 28 14:39:36 volumio volumio[1149]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. May 28 14:39:36 volumio systemd[1]: Reloading. May 28 14:39:36 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. May 28 14:39:36 volumio systemd[1]: Reloading. May 28 14:39:36 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. May 28 14:39:36 volumio systemd[1]: Reloading. May 28 14:39:36 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. May 28 14:39:36 volumio volumio[1149]: info: Creating systemd unit /etc/systemd/system/minidlna.service May 28 14:39:36 volumio systemd[1]: Reloading. May 28 14:39:36 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. May 28 14:39:37 volumio volumio[1149]: info: Setting values for "network_interface" and "model_number" in /data/plugins/music_service/minidlna/config.json May 28 14:39:37 volumio volumio[1149]: info: Setting permissions to MiniDLNA folders May 28 14:39:37 volumio sudo[3281]: pam_unix(sudo:session): session closed for user root May 28 14:39:37 volumio volumio[1149]: info: Install script completed May 28 14:39:37 volumio volumio[1149]: info: Done installing plugin. May 28 14:39:37 volumio volumio[1149]: info: Enabling plugin minidlna May 28 14:39:37 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:39:37 volumio volumio[1149]: info: [{"prettyName":"miniDLNA","name":"minidlna","category":"music_service","version":"3.1.12","icon":"fa-share-alt","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true}] May 28 14:39:37 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 28 14:39:37 volumio volumio[1149]: Plugin install end detected on script May 28 14:39:37 volumio volumio[1149]: info: Folder /tmp/plugins removed May 28 14:39:37 volumio volumio[1149]: info: Folder /tmp/downloaded_plugin.zip removed May 28 14:39:37 volumio volumio[1149]: info: Folder /data/temp removed May 28 14:39:48 volumio volumio[1149]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 14:40:24 volumio volumio[1149]: info: CALLMETHOD: music_service minidlna forceRescan R May 28 14:40:24 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: minidlna , forceRescan May 28 14:40:24 volumio sudo[3653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/^Environment=DAEMON_OPTS=$/Environment=DAEMON_OPTS=-R/ /etc/systemd/system/minidlna.service May 28 14:40:24 volumio sudo[3653]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:24 volumio sudo[3653]: pam_unix(sudo:session): session closed for user root May 28 14:40:24 volumio sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 28 14:40:24 volumio sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:24 volumio systemd[1]: Reloading. May 28 14:40:24 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. May 28 14:40:24 volumio sudo[3656]: pam_unix(sudo:session): session closed for user root May 28 14:40:24 volumio volumio[1149]: info: minidlna: systemctl daemon-reload succeeded. May 28 14:40:24 volumio sudo[3672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart minidlna.service May 28 14:40:24 volumio sudo[3672]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:24 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. May 28 14:40:24 volumio sudo[3672]: pam_unix(sudo:session): session closed for user root May 28 14:40:24 volumio volumio[1149]: info: minidlna: systemctl restart minidlna.service succeeded. May 28 14:40:24 volumio volumio[1149]: info: minidlna: Rescanning the media directories. May 28 14:40:24 volumio sudo[3677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/^Environment=DAEMON_OPTS=-R$/Environment=DAEMON_OPTS=/ /etc/systemd/system/minidlna.service May 28 14:40:24 volumio sudo[3677]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:24 volumio sudo[3677]: pam_unix(sudo:session): session closed for user root May 28 14:40:24 volumio minidlnad[3674]: [2025/05/28 14:40:24] minidlna.c:559: fatal: Error reading configuration file /data/minidlna.conf May 28 14:40:24 volumio sudo[3680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 28 14:40:24 volumio sudo[3680]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:24 volumio systemd[1]: minidlna.service: Main process exited, code=exited, status=255/EXCEPTION May 28 14:40:24 volumio systemd[1]: minidlna.service: Failed with result 'exit-code'. May 28 14:40:24 volumio systemd[1]: Reloading. May 28 14:40:24 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. May 28 14:40:24 volumio sudo[3680]: pam_unix(sudo:session): session closed for user root May 28 14:40:24 volumio volumio[1149]: info: minidlna: systemctl daemon-reload succeeded. May 28 14:40:31 volumio volumio[1149]: info: CALLMETHOD: music_service minidlna forceRescan R May 28 14:40:31 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: minidlna , forceRescan May 28 14:40:31 volumio sudo[3767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/^Environment=DAEMON_OPTS=$/Environment=DAEMON_OPTS=-R/ /etc/systemd/system/minidlna.service May 28 14:40:31 volumio sudo[3767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:31 volumio sudo[3767]: pam_unix(sudo:session): session closed for user root May 28 14:40:31 volumio sudo[3770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 28 14:40:31 volumio sudo[3770]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:31 volumio systemd[1]: Reloading. May 28 14:40:31 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. May 28 14:40:31 volumio sudo[3770]: pam_unix(sudo:session): session closed for user root May 28 14:40:31 volumio volumio[1149]: info: minidlna: systemctl daemon-reload succeeded. May 28 14:40:31 volumio sudo[3786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart minidlna.service May 28 14:40:31 volumio sudo[3786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:31 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. May 28 14:40:31 volumio sudo[3786]: pam_unix(sudo:session): session closed for user root May 28 14:40:31 volumio volumio[1149]: info: minidlna: systemctl restart minidlna.service succeeded. May 28 14:40:31 volumio volumio[1149]: info: minidlna: Rescanning the media directories. May 28 14:40:31 volumio sudo[3791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/^Environment=DAEMON_OPTS=-R$/Environment=DAEMON_OPTS=/ /etc/systemd/system/minidlna.service May 28 14:40:31 volumio sudo[3791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:31 volumio sudo[3791]: pam_unix(sudo:session): session closed for user root May 28 14:40:31 volumio minidlnad[3788]: [2025/05/28 14:40:31] minidlna.c:559: fatal: Error reading configuration file /data/minidlna.conf May 28 14:40:31 volumio systemd[1]: minidlna.service: Main process exited, code=exited, status=255/EXCEPTION May 28 14:40:31 volumio systemd[1]: minidlna.service: Failed with result 'exit-code'. May 28 14:40:31 volumio sudo[3794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 28 14:40:31 volumio sudo[3794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:40:31 volumio systemd[1]: Reloading. May 28 14:40:31 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. May 28 14:40:31 volumio sudo[3794]: pam_unix(sudo:session): session closed for user root May 28 14:40:31 volumio volumio[1149]: info: minidlna: systemctl daemon-reload succeeded. May 28 14:42:58 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:42:58 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:42:58 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:42:58 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:42:58 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:42:58 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:42:58 volumio volumio[1149]: verbose: New Socket.io Connection to 192.168.0.147:3000 from 192.168.0.218 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 28 14:42:58 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:42:58 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:42:58 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 14:42:58 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 14:43:01 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:43:01 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:43:01 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:43:01 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:01 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:01 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:43:01 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:01 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:02 volumio volumio[1149]: verbose: New Socket.io Connection to 192.168.0.147:3000 from 192.168.0.218 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 28 14:43:02 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:02 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:02 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 14:43:02 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 14:43:02 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:02 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:02 volumio sudo[4167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 14:43:02 volumio sudo[4167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:43:02 volumio sudo[4167]: pam_unix(sudo:session): session closed for user root May 28 14:43:02 volumio sudo[4170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 14:43:02 volumio sudo[4170]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:43:02 volumio sudo[4170]: pam_unix(sudo:session): session closed for user root May 28 14:43:02 volumio volumio[1149]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.218 UA: Mozilla/5.0 (Linux; Android 15; SM-S911B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::volumioGetVisibleSources May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:03 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 14:43:03 volumio volumio[1149]: info: Received Get System Info May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:43:03 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:03 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:03 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:03 volumio volumio[1149]: info: Listing playlists May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 14:43:03 volumio sudo[4173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 14:43:03 volumio sudo[4173]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:43:03 volumio sudo[4173]: pam_unix(sudo:session): session closed for user root May 28 14:43:03 volumio sudo[4176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 14:43:03 volumio sudo[4176]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 14:43:03 volumio sudo[4176]: pam_unix(sudo:session): session closed for user root May 28 14:43:03 volumio volumio[1149]: verbose: New Socket.io Connection to 192.168.0.147 from 192.168.0.218 UA: Mozilla/5.0 (Linux; Android 15; SM-S911B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::volumioGetVisibleSources May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:03 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 14:43:03 volumio volumio[1149]: info: Received Get System Info May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:43:03 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:03 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:03 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:03 volumio volumio[1149]: info: Listing playlists May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 14:43:03 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 14:43:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 28 14:43:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:43:05 volumio volumio[1149]: info: Received Get System Info May 28 14:43:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:43:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:43:05 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:43:05 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:05 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:43:05 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 28 14:43:10 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 14:43:10 volumio volumio[1149]: info: Received Get System Info May 28 14:43:10 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:43:10 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:43:10 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:43:10 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:10 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:10 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:43:13 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 14:43:14 volumio volumio[1149]: info: Retrieving Cloud Streaming UI May 28 14:43:14 volumio volumio[1149]: info: Getting Tidal Cloud Configuration May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 14:43:14 volumio volumio[1149]: info: Getting Qobuz Cloud Configuration May 28 14:43:14 volumio volumio[1149]: info: Asking plugin for UI Config May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 14:43:14 volumio volumio[1149]: info: Getting Spotify Cloud Configuration May 28 14:43:14 volumio volumio[1149]: info: Asking plugin for UI Config May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 14:43:14 volumio volumio[1149]: info: Saving Spotify Acccount May 28 14:43:14 volumio volumio[1149]: info: Got Tidal Cloud Configuration May 28 14:43:14 volumio volumio[1149]: info: Got it May 28 14:43:14 volumio volumio[1149]: info: Got it May 28 14:43:14 volumio volumio[1149]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 14:43:14 volumio volumio[1149]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::volumioGetBrowseSources May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::volumioGetBrowseSources May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::volumioGetBrowseSources May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 28 14:43:14 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 28 14:43:18 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 28 14:43:22 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 14:43:22 volumio volumio[1149]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 28 14:43:22 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 28 14:43:22 volumio volumio[1149]: info: Received Get System Version May 28 14:43:22 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 14:43:22 volumio volumio[1149]: info: Received Get System Info May 28 14:43:22 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 14:43:22 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 14:43:22 volumio volumio[1149]: info: Discovery: Getting this device information May 28 14:43:22 volumio volumio[1149]: info: CoreCommandRouter::volumioGetState May 28 14:43:22 volumio volumio[1149]: info: CorePlayQueue::getTrack 0 May 28 14:43:22 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 14:43:22 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 28 14:43:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 28 14:43:23 volumio volumio[1149]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 28 14:43:23 volumio volumio[1149]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 14:43:23 volumio volumio[1149]: Error: getaddrinfo -3007 May 28 14:43:23 volumio volumio[1149]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) May 28 14:43:23 volumio volumio[1149]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) May 28 14:43:23 volumio volumio[1149]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { May 28 14:43:23 volumio volumio[1149]: code: -3007, May 28 14:43:23 volumio volumio[1149]: errno: -3007, May 28 14:43:23 volumio volumio[1149]: syscall: 'getaddrinfo' May 28 14:43:23 volumio volumio[1149]: } May 28 14:43:23 volumio volumio[1149]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 14:43:23 volumio sudo[4221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-28 13:42 May 28 14:43:23 volumio sudo[4221]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"