Jun 29 11:19:38 volumio2 ntpd[1037]: CLOCK: time stepped by 59287.104992 Jun 29 11:19:38 volumio2 ntpd[1037]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jun 29 11:19:38 volumio2 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jun 29 11:19:38 volumio2 systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Jun 29 11:19:38 volumio2 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jun 29 11:19:38 volumio2 systemd[1]: e2scrub_all.service: Deactivated successfully. Jun 29 11:19:38 volumio2 systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Jun 29 11:19:38 volumio2 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jun 29 11:19:38 volumio2 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jun 29 11:19:38 volumio2 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: A device disappeared from network Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: Device volumio2 disappeared from network Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: A device disappeared from network Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: Device volumio disappeared from network Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: Disconnected from remote: 192.168.1.58 Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: A device disappeared from network Jun 29 11:19:38 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: adding 4b8b9102-0605-479a-b53d-47631493af23 Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: Found device Volumio2 Jun 29 11:19:38 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:19:38 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: this is already registered, 4b8b9102-0605-479a-b53d-47631493af23 Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: Found device Volumio2 Jun 29 11:19:38 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:19:38 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:19:38 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 29 11:19:38 volumio2 volumio[2683]: info: Received Get System Info Jun 29 11:19:38 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 11:19:38 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 11:19:38 volumio2 volumio[2683]: info: Discovery: Getting this device information Jun 29 11:19:38 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:19:38 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:19:38 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 11:19:39 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 29 11:19:40 volumio2 volumio[2683]: info: Adding plugin bluetooth to MyMusic Plugins Jun 29 11:19:40 volumio2 volumio[2683]: info: Adding plugin multiroom to MyMusic Plugins Jun 29 11:19:40 volumio2 volumio[2683]: info: Adding plugin metavolumio to MyMusic Plugins Jun 29 11:19:40 volumio2 volumio[2683]: info: Adding plugin cd_controller to MyMusic Plugins Jun 29 11:19:40 volumio2 volumio[2683]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 29 11:19:40 volumio2 volumio[2683]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 29 11:19:40 volumio2 volumio[2683]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 29 11:19:40 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 11:19:40 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 11:19:40 volumio2 volumio[2683]: info: Starting MyVolumio Remote Streaming Endpoints Jun 29 11:19:40 volumio2 volumio[2683]: info: MyVolumio not started Jun 29 11:19:40 volumio2 volumio[2683]: info: Initializing device activation check Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 29 11:19:40 volumio2 volumio[2683]: info: Streaming services startup Jun 29 11:19:40 volumio2 volumio[2683]: info: Starting Streaming Daemon Jun 29 11:19:40 volumio2 sudo[2997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 29 11:19:40 volumio2 sudo[2997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 29 11:19:40 volumio2 volumio[2683]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 29 11:19:40 volumio2 sudo[2997]: pam_unix(sudo:session): session closed for user root Jun 29 11:19:40 volumio2 sudo[3004]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 29 11:19:40 volumio2 sudo[3004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 29 11:19:40 volumio2 sudo[3004]: pam_unix(sudo:session): session closed for user root Jun 29 11:19:40 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Jun 29 11:19:40 volumio2 sudo[3006]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 29 11:19:40 volumio2 sudo[3006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 29 11:19:40 volumio2 volumio[2683]: error: Cannot start Volumio Streaming Daemon Jun 29 11:19:40 volumio2 volumio[2683]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 29 11:19:40 volumio2 volumio[2683]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 29 11:19:40 volumio2 sudo[3006]: pam_unix(sudo:session): session closed for user root Jun 29 11:19:40 volumio2 sudo[3010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 29 11:19:40 volumio2 sudo[3010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 29 11:19:40 volumio2 sudo[3010]: pam_unix(sudo:session): session closed for user root Jun 29 11:19:40 volumio2 volumio[2683]: info: Upmpdcli Daemon Started Jun 29 11:19:42 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:19:42 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:19:43 volumio2 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299522, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 29 11:19:45 volumio2 nmbd[2246]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299618, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 29 11:19:45 volumio2 nmbd[2246]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299661, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 29 11:19:45 volumio2 nmbd[2246]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299681, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 29 11:19:45 volumio2 nmbd[2246]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299701, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 29 11:19:45 volumio2 nmbd[2246]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299716, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 29 11:19:45 volumio2 nmbd[2246]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299732, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 29 11:19:45 volumio2 nmbd[2246]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299745, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 29 11:19:45 volumio2 nmbd[2246]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299760, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 29 11:19:45 volumio2 nmbd[2246]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299775, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 29 11:19:45 volumio2 nmbd[2246]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299790, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 29 11:19:45 volumio2 nmbd[2246]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Jun 29 11:19:45 volumio2 nmbd[2246]: [2025/06/29 11:19:45.299804, 0] ../../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Jun 29 11:19:45 volumio2 nmbd[2246]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Jun 29 11:19:46 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 29 11:19:47 volumio2 volumio[2683]: info: CoreCommandRouter::Close All Modals sent Jun 29 11:19:47 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Jun 29 11:19:49 volumio2 nmbd[2246]: [2025/06/29 11:19:49.306655, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Jun 29 11:19:49 volumio2 nmbd[2246]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Jun 29 11:19:54 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 29 11:19:54 volumio2 volumio[2683]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 29 11:19:54 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 29 11:19:54 volumio2 volumio[2683]: info: Received Get System Version Jun 29 11:19:54 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 29 11:19:54 volumio2 volumio[2683]: info: Received Get System Info Jun 29 11:19:54 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 11:19:54 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 11:19:54 volumio2 volumio[2683]: info: Discovery: Getting this device information Jun 29 11:19:54 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:19:54 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:19:54 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 11:19:55 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 29 11:19:55 volumio2 volumio[2683]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 29 11:19:55 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:19:55 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:19:55 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetBrowseSources Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 29 11:20:00 volumio2 volumio[2683]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 29 11:20:00 volumio2 volumio[2683]: info: Completed starting MyVolumio Plugin Jun 29 11:20:00 volumio2 volumio[2683]: [Metrics] CommandRouter: 30s 970.26ms Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::volumiosetStartupVolume Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::Close All Modals sent Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::Close All Modals sent Jun 29 11:20:00 volumio2 volumio[2683]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 29 11:20:00 volumio2 volumio[2683]: aplay: main:831: audio open error: Unknown error 524 Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 29 11:20:00 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 29 11:20:01 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 29 11:20:01 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 29 11:20:01 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 29 11:20:07 volumio2 volumio[2683]: info: BOOT COMPLETED Jun 29 11:20:27 volumio2 sudo[3089]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 29 11:20:27 volumio2 sudo[3089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 29 11:20:27 volumio2 sudo[3091]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 29 11:20:27 volumio2 sudo[3091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 29 11:20:27 volumio2 sudo[3089]: pam_unix(sudo:session): session closed for user root Jun 29 11:20:27 volumio2 sudo[3091]: pam_unix(sudo:session): session closed for user root Jun 29 11:20:27 volumio2 volumio[2683]: verbose: New Socket.io Connection to 192.168.1.74 from 192.168.1.123 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetVisibleSources Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:20:28 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 29 11:20:28 volumio2 volumio[2683]: info: Received Get System Info Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 11:20:28 volumio2 volumio[2683]: info: Discovery: Getting this device information Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:20:28 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 11:20:28 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:20:28 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:20:28 volumio2 volumio[2683]: info: Listing playlists Jun 29 11:20:28 volumio2 volumio[2683]: info: Discovery: adding 7f2aca89-714c-43ac-875e-19af0330631d Jun 29 11:20:28 volumio2 volumio[2683]: info: Discovery: Found device Volumio Jun 29 11:20:28 volumio2 volumio[2683]: info: Discovery: Connecting to remote: 192.168.1.58 Jun 29 11:20:28 volumio2 volumio[2683]: info: Discovery: Connected to remote: 192.168.1.58 Jun 29 11:20:29 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 29 11:20:29 volumio2 volumio-remote-updater[2097]: Test mode disabled Jun 29 11:20:29 volumio2 volumio-remote-updater[2097]: Alpha mode disabled Jun 29 11:20:29 volumio2 volumio-remote-updater[2097]: Alpha legacy test mode disabled Jun 29 11:20:29 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 29 11:20:29 volumio2 volumio[2683]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 29 11:20:29 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 29 11:20:29 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 29 11:20:29 volumio2 volumio[2683]: info: Received Get System Info Jun 29 11:20:29 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 11:20:29 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 11:20:29 volumio2 volumio[2683]: info: Discovery: Getting this device information Jun 29 11:20:29 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:20:29 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:20:29 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 11:20:29 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 29 11:20:30 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 29 11:20:30 volumio2 volumio[2683]: info: MyVolumio login type: Token Jun 29 11:20:30 volumio2 volumio[2683]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 29 11:20:30 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:20:30 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:20:31 volumio2 volumio[2683]: info: MyVolumio token set successfully Jun 29 11:20:31 volumio2 volumio[2683]: info: MYVOLUMIO: Adding device Jun 29 11:20:31 volumio2 volumio[2683]: info: MYVOLUMIO: Evaluating Server Jun 29 11:20:31 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 29 11:20:31 volumio2 volumio[2683]: info: Received Get System Info Jun 29 11:20:31 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 11:20:31 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 11:20:31 volumio2 volumio[2683]: info: Discovery: Getting this device information Jun 29 11:20:31 volumio2 volumio[2683]: info: CoreCommandRouter::volumioGetState Jun 29 11:20:31 volumio2 volumio[2683]: info: CorePlayQueue::getTrack 0 Jun 29 11:20:31 volumio2 volumio[2683]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 11:20:31 volumio2 volumio[2683]: info: MyVolumio status changed Jun 29 11:20:31 volumio2 volumio[2683]: info: Streaming services startup Jun 29 11:20:31 volumio2 volumio[2683]: info: Starting Streaming Daemon Jun 29 11:20:31 volumio2 volumio[2683]: info: Removing browser output: myVolumio user plan is not superstar Jun 29 11:20:31 volumio2 volumio[2683]: info: Removing audio output: Jun 29 11:20:31 volumio2 volumio[2683]: info: Stoppping Tunnel 1 Jun 29 11:20:31 volumio2 sudo[3128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 29 11:20:31 volumio2 sudo[3128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 29 11:20:31 volumio2 sudo[3130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 29 11:20:31 volumio2 sudo[3130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 29 11:20:31 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 29 11:20:31 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 29 11:20:31 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 29 11:20:31 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 29 11:20:31 volumio2 sudo[3128]: pam_unix(sudo:session): session closed for user root Jun 29 11:20:31 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 29 11:20:31 volumio2 volumio[2683]: error: Cannot start Volumio Streaming Daemon Jun 29 11:20:31 volumio2 dbus-daemon[882]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.53' (uid=0 pid=3134 comm="timedatectl show --property=NTPSynchronized --valu") Jun 29 11:20:31 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 29 11:20:31 volumio2 volumio[2683]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 29 11:20:31 volumio2 volumio[2683]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 29 11:20:31 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 29 11:20:31 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 29 11:20:31 volumio2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 29 11:20:31 volumio2 sudo[3130]: pam_unix(sudo:session): session closed for user root Jun 29 11:20:31 volumio2 volumio[2683]: info: Remote SSH Stopped Jun 29 11:20:31 volumio2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 29 11:20:31 volumio2 dbus-daemon[882]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 29 11:20:31 volumio2 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 29 11:20:31 volumio2 setdatetime-helper.sh[3133]: Time is not synchronized. Attempting to sync... Jun 29 11:20:31 volumio2 sudo[3142]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 29 Jun 2025 09:20:31 GMT#015' Jun 29 11:20:31 volumio2 sudo[3142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 29 11:20:31 volumio2 setdatetime-helper.sh[3143]: Sun Jun 29 11:20:31 CEST 2025 Jun 29 11:20:31 volumio2 sudo[3142]: pam_unix(sudo:session): session closed for user root Jun 29 11:20:31 volumio2 setdatetime-helper.sh[3133]: Time synchronized successfully. Jun 29 11:20:31 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 29 11:20:31 volumio2 systemd-journald[443]: Time jumped backwards, rotating. Jun 29 11:20:31 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 29 11:20:31 volumio2 volumio[2683]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Jun 29 11:20:31 volumio2 volumio[2683]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 11:20:31 volumio2 volumio[2683]: Error: Unable to resolve or reject the same promise twice Jun 29 11:20:31 volumio2 volumio[2683]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 29 11:20:31 volumio2 volumio[2683]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jun 29 11:20:31 volumio2 volumio[2683]: at Socket.emit (node:events:514:28) Jun 29 11:20:31 volumio2 volumio[2683]: at addChunk (node:internal/streams/readable:343:12) Jun 29 11:20:31 volumio2 volumio[2683]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 29 11:20:31 volumio2 volumio[2683]: at Readable.push (node:internal/streams/readable:253:10) Jun 29 11:20:31 volumio2 volumio[2683]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 29 11:20:31 volumio2 volumio[2683]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 11:20:31 volumio2 sudo[3159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-29 11:19' Jun 29 11:20:31 volumio2 sudo[3159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f9454895cc8e2f1971c705a815a7226e021e37d7" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 28 16:48:46 UTC 2025" VOLUMIO_VERSION="4.014" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1b557e4869cd2eea14f4113140ecea1c"