-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-03-15 12:25:36 CET. -- Mar 15 12:24:03 hifipi systemd-timedated[3308]: Changed local time to Sat Mar 15 12:24:03 2025 Mar 15 12:24:03 hifipi sudo[3306]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:03 hifipi volumio-time-update[660]: volumio-time-update-util: System time updated successfully. Mar 15 12:24:03 hifipi systemd[1]: Started Volumio Time Update Utility. Mar 15 12:24:03 hifipi volumio[914]: info: Discovery: A device disappeared from network Mar 15 12:24:03 hifipi systemd[1]: Reached target Multi-User System. Mar 15 12:24:03 hifipi systemd[1]: Reached target Graphical Interface. Mar 15 12:24:03 hifipi systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 15 12:24:03 hifipi systemd[1]: Starting Daily apt download activities... Mar 15 12:24:03 hifipi volumio[914]: info: Discovery: adding 28410fe5-867e-4ca2-a089-497ba87177c8 Mar 15 12:24:03 hifipi volumio[914]: info: Discovery: Found device hifipi Mar 15 12:24:03 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:03 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:03 hifipi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 15 12:24:03 hifipi systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.185451, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 15 12:24:03 hifipi nmbd[2810]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.185803, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 15 12:24:03 hifipi nmbd[2810]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.185895, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Mar 15 12:24:03 hifipi nmbd[2810]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.185990, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 15 12:24:03 hifipi nmbd[2810]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.186066, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Mar 15 12:24:03 hifipi nmbd[2810]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.186147, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 15 12:24:03 hifipi nmbd[2810]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.186221, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Mar 15 12:24:03 hifipi nmbd[2810]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.186290, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 15 12:24:03 hifipi nmbd[2810]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.186351, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Mar 15 12:24:03 hifipi nmbd[2810]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.186431, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 15 12:24:03 hifipi nmbd[2810]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Mar 15 12:24:03 hifipi nmbd[2810]: [2025/03/15 12:24:03.186498, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Mar 15 12:24:03 hifipi nmbd[2810]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Mar 15 12:24:03 hifipi systemd[1]: apt-daily.service: Succeeded. Mar 15 12:24:03 hifipi systemd[1]: Started Daily apt download activities. Mar 15 12:24:03 hifipi systemd[1]: Starting Daily apt upgrade and clean activities... Mar 15 12:24:04 hifipi systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 15 12:24:04 hifipi systemd[1]: Started Daily apt upgrade and clean activities. Mar 15 12:24:04 hifipi systemd[1]: Startup finished in 37.632s (kernel) + 7min 22.019s (userspace) = 7min 59.651s. Mar 15 12:24:05 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Mar 15 12:24:05 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Mar 15 12:24:05 hifipi sudo[3423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 12:24:05 hifipi sudo[3423]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:05 hifipi sudo[3423]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:05 hifipi sudo[3426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 15 12:24:05 hifipi sudo[3426]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:05 hifipi sudo[3426]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 15 12:24:06 hifipi volumio[914]: info: Adding plugin bluetooth to MyMusic Plugins Mar 15 12:24:06 hifipi volumio[914]: info: Adding plugin multiroom to MyMusic Plugins Mar 15 12:24:06 hifipi volumio[914]: info: Adding plugin metavolumio to MyMusic Plugins Mar 15 12:24:06 hifipi volumio[914]: info: Adding plugin cd_controller to MyMusic Plugins Mar 15 12:24:06 hifipi volumio[914]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 15 12:24:06 hifipi volumio[914]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 15 12:24:06 hifipi volumio[914]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 15 12:24:07 hifipi volumio[914]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 15 12:24:07 hifipi volumio[914]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 15 12:24:07 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 12:24:07 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 12:24:07 hifipi volumio[914]: info: Starting MyVolumio Remote Streaming Endpoints Mar 15 12:24:07 hifipi volumio[914]: info: MyVolumio not started Mar 15 12:24:07 hifipi volumio[914]: info: Initializing device activation check Mar 15 12:24:07 hifipi volumio[914]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 15 12:24:07 hifipi volumio[914]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 15 12:24:07 hifipi volumio[914]: info: Starting Streaming Service Transparent Proxy Mar 15 12:24:07 hifipi volumio[914]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 15 12:24:07 hifipi volumio[914]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 15 12:24:07 hifipi volumio[914]: info: Streaming services startup Mar 15 12:24:07 hifipi volumio[914]: info: Starting Streaming Daemon Mar 15 12:24:07 hifipi sudo[3437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 15 12:24:07 hifipi sudo[3437]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:07 hifipi sudo[3437]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:07 hifipi volumio[914]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 15 12:24:08 hifipi volumio[914]: error: Cannot start Volumio Streaming Daemon Mar 15 12:24:08 hifipi volumio[914]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 15 12:24:08 hifipi volumio[914]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 15 12:24:08 hifipi volumio[914]: info: Volumio Network Manager: Network status updated: 2 Mar 15 12:24:08 hifipi volumio[914]: STREAMING PROXY: Starting server on port 3245 Mar 15 12:24:08 hifipi volumio[914]: Node JS runtime: 14 Mar 15 12:24:10 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Mar 15 12:24:10 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Mar 15 12:24:10 hifipi volumio[914]: info: Refreshing Cached IP Addresses Mar 15 12:24:10 hifipi sudo[3463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 15 12:24:10 hifipi sudo[3463]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:10 hifipi sudo[3463]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:10 hifipi sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 12:24:10 hifipi sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:10 hifipi sudo[3465]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:10 hifipi volumio[914]: error: Upnp client error: Error: This socket has been ended by the other party Mar 15 12:24:10 hifipi sudo[3468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 12:24:10 hifipi sudo[3468]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:10 hifipi sudo[3468]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:20 hifipi sudo[3486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 12:24:20 hifipi sudo[3486]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:20 hifipi sudo[3486]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:20 hifipi sudo[3488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 12:24:20 hifipi sudo[3488]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:20 hifipi sudo[3488]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:20 hifipi sudo[3492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 15 12:24:20 hifipi sudo[3492]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:20 hifipi sudo[3492]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:20 hifipi volumio[914]: info: Upmpdcli Daemon Started Mar 15 12:24:22 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 12:24:22 hifipi volumio[914]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 15 12:24:22 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:22 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:23 hifipi ntpd[817]: Soliciting pool server 130.162.220.39 Mar 15 12:24:23 hifipi ntpd[817]: Soliciting pool server 185.248.188.98 Mar 15 12:24:23 hifipi ntpd[817]: Soliciting pool server 195.201.137.97 Mar 15 12:24:24 hifipi ntpd[817]: Soliciting pool server 141.144.246.224 Mar 15 12:24:24 hifipi ntpd[817]: Soliciting pool server 162.159.200.123 Mar 15 12:24:24 hifipi ntpd[817]: Soliciting pool server 158.220.97.17 Mar 15 12:24:25 hifipi ntpd[817]: Soliciting pool server 5.75.181.179 Mar 15 12:24:25 hifipi ntpd[817]: Soliciting pool server 176.9.8.206 Mar 15 12:24:25 hifipi ntpd[817]: Soliciting pool server 185.252.140.125 Mar 15 12:24:26 hifipi ntpd[817]: Soliciting pool server 45.9.61.155 Mar 15 12:24:26 hifipi ntpd[817]: Soliciting pool server 167.235.70.245 Mar 15 12:24:26 hifipi ntpd[817]: Soliciting pool server 5.45.97.204 Mar 15 12:24:27 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 12:24:27 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 12:24:27 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 15 12:24:27 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 15 12:24:27 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 15 12:24:27 hifipi volumio[914]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 12:24:27 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 12:24:27 hifipi ntpd[817]: Soliciting pool server 2a01:4f8:160:43aa::2 Mar 15 12:24:29 hifipi ntpd[817]: Soliciting pool server 217.91.44.17 Mar 15 12:24:30 hifipi ntpd[817]: Soliciting pool server 167.86.86.192 Mar 15 12:24:31 hifipi ntpd[817]: Soliciting pool server 88.198.7.62 Mar 15 12:24:32 hifipi ntpd[817]: receive: Unexpected origin timestamp 0xeb7fe1ef.c40f9753 does not match aorg 0000000000.00000000 from server@158.220.97.17 xmt 0xeb7fe1f0.575790ea Mar 15 12:24:32 hifipi ntpd[817]: receive: Unexpected origin timestamp 0xeb7fe1ef.c40cbb58 does not match aorg 0000000000.00000000 from server@167.235.70.245 xmt 0xeb7fe1f0.57cb7a28 Mar 15 12:24:32 hifipi ntpd[817]: receive: Unexpected origin timestamp 0xeb7fe1ef.c41145fa does not match aorg 0000000000.00000000 from server@185.248.188.98 xmt 0xeb7fe1f0.5869ad15 Mar 15 12:24:33 hifipi ntpd[817]: Soliciting pool server 45.142.247.74 Mar 15 12:24:33 hifipi systemd[1]: systemd-timedated.service: Succeeded. Mar 15 12:24:41 hifipi wpa_supplicant[3162]: RRM: Ignoring radio measurement request: Not RRM network Mar 15 12:24:41 hifipi sudo[3529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 12:24:41 hifipi sudo[3529]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:41 hifipi sudo[3529]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:41 hifipi sudo[3531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 12:24:41 hifipi sudo[3531]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:41 hifipi sudo[3531]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:42 hifipi sudo[3535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 12:24:42 hifipi sudo[3535]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:42 hifipi sudo[3535]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:42 hifipi sudo[3538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 12:24:42 hifipi sudo[3538]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:42 hifipi sudo[3538]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:42 hifipi volumio[914]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:136.0) Gecko/20100101 Firefox/136.0 Engine version: 3 Transport: polling Total Clients: 4 Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::volumioGetVisibleSources Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:42 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 15 12:24:42 hifipi volumio[914]: info: Received Get System Info Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 12:24:42 hifipi volumio[914]: info: Discovery: Getting this device information Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:42 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:42 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:42 hifipi volumio[914]: info: Listing playlists Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 12:24:42 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 15 12:24:43 hifipi wpa_supplicant[3162]: RRM: Ignoring radio measurement request: Not RRM network Mar 15 12:24:43 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:43 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:44 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 15 12:24:44 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 12:24:44 hifipi volumio[914]: info: Received Get System Info Mar 15 12:24:44 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 12:24:44 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 12:24:44 hifipi volumio[914]: info: Discovery: Getting this device information Mar 15 12:24:44 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:44 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:44 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 12:24:44 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 15 12:24:44 hifipi volumio[914]: info: MyVolumio login type: Token Mar 15 12:24:45 hifipi volumio[914]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 15 12:24:45 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 12:24:45 hifipi volumio[914]: info: Received Get System Info Mar 15 12:24:45 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 12:24:45 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 12:24:45 hifipi volumio[914]: info: Discovery: Getting this device information Mar 15 12:24:45 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:45 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:45 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 12:24:45 hifipi wpa_supplicant[3162]: RRM: Ignoring radio measurement request: Not RRM network Mar 15 12:24:47 hifipi volumio[914]: info: MyVolumio token set successfully Mar 15 12:24:47 hifipi volumio[914]: info: MYVOLUMIO: Adding device Mar 15 12:24:47 hifipi volumio[914]: info: MYVOLUMIO: Evaluating Server Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 15 12:24:47 hifipi volumio[914]: info: MyVolumio status changed Mar 15 12:24:47 hifipi volumio[914]: info: Streaming services startup Mar 15 12:24:47 hifipi volumio[914]: info: Starting Streaming Daemon Mar 15 12:24:47 hifipi volumio[914]: info: Removing browser output: myVolumio user plan is not superstar Mar 15 12:24:47 hifipi volumio[914]: info: Removing audio output: Mar 15 12:24:47 hifipi volumio[914]: info: Stoppping Tunnel 1 Mar 15 12:24:47 hifipi sudo[3578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 15 12:24:47 hifipi sudo[3578]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:47 hifipi wpa_supplicant[3162]: RRM: Ignoring radio measurement request: Not RRM network Mar 15 12:24:47 hifipi sudo[3587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 15 12:24:47 hifipi sudo[3587]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:24:47 hifipi sudo[3578]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:47 hifipi volumio[914]: error: Cannot start Volumio Streaming Daemon Mar 15 12:24:47 hifipi volumio[914]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 15 12:24:47 hifipi volumio[914]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 15 12:24:47 hifipi sudo[3587]: pam_unix(sudo:session): session closed for user root Mar 15 12:24:47 hifipi volumio[914]: info: Remote SSH Stopped Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 12:24:47 hifipi volumio[914]: info: Received Get System Info Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 12:24:47 hifipi volumio[914]: info: Discovery: Getting this device information Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:47 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 12:24:47 hifipi volumio[914]: info: Setting Geolocation for MyVolumio to eu4 Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 12:24:47 hifipi volumio[914]: info: Received Get System Info Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 12:24:47 hifipi volumio[914]: info: Discovery: Getting this device information Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:24:47 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:24:47 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 12:24:48 hifipi volumio[914]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 15 12:24:48 hifipi volumio[914]: info: Updating MyVolumio device info Mar 15 12:24:48 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 12:24:48 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 12:24:48 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 12:24:49 hifipi volumio[914]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 15 12:24:51 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 12:24:51 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 15 12:24:52 hifipi volumio[914]: info: MYVOLUMIO: Adding device Mar 15 12:24:52 hifipi volumio[914]: info: MYVOLUMIO: Evaluating Server Mar 15 12:24:52 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 15 12:24:52 hifipi volumio[914]: info: Setting Geolocation for MyVolumio to eu6 Mar 15 12:24:52 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 12:24:52 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 12:24:53 hifipi volumio[914]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 15 12:24:53 hifipi volumio[914]: info: Updating MyVolumio device info Mar 15 12:24:53 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 12:24:54 hifipi volumio[914]: info: CoreCommandRouter::Close All Modals sent Mar 15 12:24:54 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Mar 15 12:24:55 hifipi volumio[914]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 15 12:25:00 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 12:25:00 hifipi volumio[914]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 15 12:25:00 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 15 12:25:00 hifipi volumio[914]: info: Received Get System Version Mar 15 12:25:00 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 12:25:00 hifipi volumio[914]: info: Received Get System Info Mar 15 12:25:00 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 12:25:00 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 12:25:00 hifipi volumio[914]: info: Discovery: Getting this device information Mar 15 12:25:00 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:00 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:00 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 12:25:02 hifipi volumio[914]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Mar 15 12:25:04 hifipi volumio[914]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Mar 15 12:25:04 hifipi volumio[914]: info: Folder /tmp/plugins removed Mar 15 12:25:05 hifipi volumio[914]: info: Check plugin dependencies Mar 15 12:25:05 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 12:25:05 hifipi volumio[914]: info: Checking if plugin already exists Mar 15 12:25:05 hifipi volumio[914]: info: Rename folder Mar 15 12:25:05 hifipi volumio[914]: info: Folder /tmp/downloaded_plugin.zip removed Mar 15 12:25:05 hifipi volumio[914]: info: Move to category Mar 15 12:25:06 hifipi volumio[914]: info: Checking if install.sh is present Mar 15 12:25:06 hifipi volumio[914]: info: Executing install.sh Mar 15 12:25:06 hifipi sudo[3669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Mar 15 12:25:06 hifipi sudo[3669]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:25:06 hifipi volumio[914]: info: Installing Go-librespot Mar 15 12:25:06 hifipi volumio[914]: info: Checking old vollibrespot installs Mar 15 12:25:06 hifipi systemd[1]: Reloading. Mar 15 12:25:06 hifipi 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. Mar 15 12:25:06 hifipi volumio[914]: info: Downloading daemon Mar 15 12:25:08 hifipi volumio[914]: info: Creating directories Mar 15 12:25:08 hifipi volumio[914]: info: Creating data directory Mar 15 12:25:08 hifipi volumio[914]: info: Creating Start Script Mar 15 12:25:08 hifipi systemd[1]: Reloading. Mar 15 12:25:08 hifipi 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. Mar 15 12:25:09 hifipi sudo[3669]: pam_unix(sudo:session): session closed for user root Mar 15 12:25:09 hifipi volumio[914]: Plugin install end detected on script Mar 15 12:25:09 hifipi volumio[914]: info: Install script completed Mar 15 12:25:09 hifipi volumio[914]: info: Adding reference to registry Mar 15 12:25:09 hifipi volumio[914]: info: Done installing plugin. Mar 15 12:25:09 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 12:25:09 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 15 12:25:09 hifipi volumio[914]: info: Folder /tmp/downloaded_plugin.zip removed Mar 15 12:25:09 hifipi volumio[914]: info: Folder /tmp/plugins removed Mar 15 12:25:09 hifipi volumio[914]: info: Folder /data/temp removed Mar 15 12:25:11 hifipi volumio[914]: info: Enabling plugin spop Mar 15 12:25:11 hifipi volumio[914]: info: Loading plugin "spop"... Mar 15 12:25:11 hifipi volumio[914]: info: PLUGIN START: spop Mar 15 12:25:11 hifipi volumio[914]: info: Creating Spotify config file Mar 15 12:25:11 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 12:25:11 hifipi volumio[914]: info: Done. Mar 15 12:25:11 hifipi volumio[914]: info: Spotify config file written Mar 15 12:25:11 hifipi volumio[914]: info: No need to fix Spotify hosts Mar 15 12:25:11 hifipi sudo[3734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 15 12:25:11 hifipi sudo[3734]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:25:12 hifipi systemd[1]: Started go-librespot Daemon. Mar 15 12:25:12 hifipi go-librespot[3736]: go-librespot daemon starting... Mar 15 12:25:12 hifipi sudo[3734]: pam_unix(sudo:session): session closed for user root Mar 15 12:25:12 hifipi go-librespot[3736]: time="2025-03-15T12:25:12+01:00" level=info msg="running go-librespot 0.2.0" Mar 15 12:25:12 hifipi go-librespot[3736]: time="2025-03-15T12:25:12+01:00" level=debug msg="no app state found" Mar 15 12:25:12 hifipi go-librespot[3736]: time="2025-03-15T12:25:12+01:00" level=debug msg="stored credentials not found" Mar 15 12:25:12 hifipi go-librespot[3736]: time="2025-03-15T12:25:12+01:00" level=info msg="generated new device id: 73e09eeaf6f0ccefaa69fe441531075b33fe9ce4" Mar 15 12:25:12 hifipi go-librespot[3736]: time="2025-03-15T12:25:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 15 12:25:12 hifipi go-librespot[3736]: time="2025-03-15T12:25:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 15 12:25:12 hifipi go-librespot[3736]: time="2025-03-15T12:25:12+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 15 12:25:12 hifipi go-librespot[3736]: time="2025-03-15T12:25:12+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 15 12:25:12 hifipi go-librespot[3736]: time="2025-03-15T12:25:12+01:00" level=info msg="zeroconf server listening on port 39777" Mar 15 12:25:14 hifipi volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 12:25:15 hifipi volumio[914]: info: go-librespot daemon successfully initialized Mar 15 12:25:18 hifipi volumio[914]: info: Initializing connection to go-librespot Websocket Mar 15 12:25:18 hifipi go-librespot[3736]: time="2025-03-15T12:25:18+01:00" level=debug msg="new websocket client" Mar 15 12:25:18 hifipi volumio[914]: info: Connection to go-librespot Websocket established Mar 15 12:25:20 hifipi volumio[914]: info: Received OAUTH Data Mar 15 12:25:20 hifipi volumio[914]: info: Executing Spotify Oauth Login Mar 15 12:25:20 hifipi volumio[914]: info: Saving Spotify Refresh Token Mar 15 12:25:20 hifipi volumio[914]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 15 12:25:20 hifipi volumio[914]: SPOTIFY: BQCdTwlK95TfZ8dOxRdw_5ouDUYg4sq305E2vm83dY6uGM3e9apOsfZujxYCJ7cDBsix7VWnYJr3WoVRZo3nwPsr8dZEmO1WSgr4sZTNC6ca5_zDr73HkAQJ8K806-dk1aGgTtzYK0ilG9uh_py7n6kbXKktNkPbx2xsxcOA5BC_WFNpXZ63BeYH8OcMzhuGo4k9PEQaImc3CbA8vkvtS1AroAgkQXg2CQvPAhYrL1TVAAXY0YyCaB6Wr-M3FPGUYYZytBLHi5FUkaHAkUSk0NmWZQ8 Mar 15 12:25:20 hifipi volumio[914]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 15 12:25:20 hifipi volumio[914]: info: New Spotify access token = BQCdTwlK95TfZ8dOxRdw_5ouDUYg4sq305E2vm83dY6uGM3e9apOsfZujxYCJ7cDBsix7VWnYJr3WoVRZo3nwPsr8dZEmO1WSgr4sZTNC6ca5_zDr73HkAQJ8K806-dk1aGgTtzYK0ilG9uh_py7n6kbXKktNkPbx2xsxcOA5BC_WFNpXZ63BeYH8OcMzhuGo4k9PEQaImc3CbA8vkvtS1AroAgkQXg2CQvPAhYrL1TVAAXY0YyCaB6Wr-M3FPGUYYZytBLHi5FUkaHAkUSk0NmWZQ8 Mar 15 12:25:20 hifipi volumio[914]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 15 12:25:21 hifipi sudo[3759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 12:25:21 hifipi sudo[3759]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:25:21 hifipi sudo[3759]: pam_unix(sudo:session): session closed for user root Mar 15 12:25:21 hifipi sudo[3762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 12:25:21 hifipi sudo[3762]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:25:21 hifipi sudo[3762]: pam_unix(sudo:session): session closed for user root Mar 15 12:25:21 hifipi volumio[914]: info: Getting Spotify volume Mar 15 12:25:21 hifipi volumio[914]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:21 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:21 hifipi volumio[914]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 15 12:25:21 hifipi volumio[914]: SPOTIFY: SPOTIFY VOLUME undefined Mar 15 12:25:21 hifipi volumio[914]: SPOTIFY: VOLUMIO VOLUME 86 Mar 15 12:25:21 hifipi volumio[914]: info: Aligning Spotify Volume to Volumio Volume Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:21 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:21 hifipi volumio[914]: info: Setting Spotify Volume from Volumio: 86 Mar 15 12:25:21 hifipi volumio[914]: SPOTIFY: User informations: {"country":"DE","display_name":"carsten.goldmann","email":"carsten.goldmann@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/carsten.goldmann"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/carsten.goldmann","id":"carsten.goldmann","images":[],"product":"premium","type":"user","uri":"spotify:user:carsten.goldmann"} Mar 15 12:25:21 hifipi volumio[914]: info: Creating Spotify config file Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 12:25:21 hifipi volumio[914]: info: Spotify config file written Mar 15 12:25:21 hifipi sudo[3765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 15 12:25:21 hifipi sudo[3765]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 12:25:21 hifipi volumio[914]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:136.0) Gecko/20100101 Firefox/136.0 Engine version: 3 Transport: polling Total Clients: 5 Mar 15 12:25:21 hifipi systemd[1]: Stopping go-librespot Daemon... Mar 15 12:25:21 hifipi systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 15 12:25:21 hifipi systemd[1]: go-librespot-daemon.service: Succeeded. Mar 15 12:25:21 hifipi systemd[1]: Stopped go-librespot Daemon. Mar 15 12:25:21 hifipi volumio[914]: info: Connection to go-librespot Websocket closed Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 12:25:21 hifipi systemd[1]: Started go-librespot Daemon. Mar 15 12:25:21 hifipi go-librespot[3767]: go-librespot daemon starting... Mar 15 12:25:21 hifipi sudo[3765]: pam_unix(sudo:session): session closed for user root Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=info msg="running go-librespot 0.2.0" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="app state loaded" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="stored credentials not found" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 15 12:25:21 hifipi volumio[914]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 15 12:25:21 hifipi volumio[914]: SPOTIFY: BQCG49Xe3mYyWQqQmTsk98l2t0PlKMTbeuI_iDGSZpWBGaINo9ovJnslf3dKd148gtovUTt5quUfi6M6f15pHDp788JRX1LuCAbw62ZG9QimyxHyWPrnXCeqWz9gUo0xUMDGxVexmkjp6KP0uQWm5NZHceo-9uKZRUYmB2ZhVl8khFbwo06UjDgaE7_egbP3tTKvuMCW9kzOpv91pboNj0bYCvt5kRznMPErewnhHOe0W6Kn447UgBY3XUyf6iVOQTuuCwJx0zAYDjyRUCgZvOM1jgY Mar 15 12:25:21 hifipi volumio[914]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 15 12:25:21 hifipi volumio[914]: info: New Spotify access token = BQCG49Xe3mYyWQqQmTsk98l2t0PlKMTbeuI_iDGSZpWBGaINo9ovJnslf3dKd148gtovUTt5quUfi6M6f15pHDp788JRX1LuCAbw62ZG9QimyxHyWPrnXCeqWz9gUo0xUMDGxVexmkjp6KP0uQWm5NZHceo-9uKZRUYmB2ZhVl8khFbwo06UjDgaE7_egbP3tTKvuMCW9kzOpv91pboNj0bYCvt5kRznMPErewnhHOe0W6Kn447UgBY3XUyf6iVOQTuuCwJx0zAYDjyRUCgZvOM1jgY Mar 15 12:25:21 hifipi volumio[914]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::volumioGetVisibleSources Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:21 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 15 12:25:21 hifipi volumio[914]: info: Received Get System Info Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 12:25:21 hifipi volumio[914]: info: Discovery: Getting this device information Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:21 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:21 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:21 hifipi volumio[914]: info: Listing playlists Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=info msg="zeroconf server listening on port 39619" Mar 15 12:25:21 hifipi volumio[914]: SPOTIFY: User informations: {"country":"DE","display_name":"carsten.goldmann","email":"carsten.goldmann@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/carsten.goldmann"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/carsten.goldmann","id":"carsten.goldmann","images":[],"product":"premium","type":"user","uri":"spotify:user:carsten.goldmann"} Mar 15 12:25:21 hifipi volumio[914]: info: Spotify Successfully logged in Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 12:25:21 hifipi volumio[914]: info: [1742037921402] CoreMusicLibrary::Adding element Spotify Mar 15 12:25:21 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 12:25:21 hifipi volumio[914]: Cannot find translation for source Spotify Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="obtained new client token: AADBsNvIjSzScZGtbxi6ezV2Xi6XtkQ3Wxb4vKMw/U0MTFK+g24v2ZQt0UR/Wuw1L6MVrwvtWkcQ2KeFZQt44ImaKqPbbkGR1CHmW4iBlA6UFqlth825AcKZ9iGsEMI01zVdXCzukKdaapVkGjt8PpeABuycXImJU6Di54h/bYgdUSdUSXlIwGra+kRNekQPAGIsxsBLdQDQvGHA99hSCsQpP1MGeLUZaTjchMDOEmlE+Vi5OYe5a+7MAQ==" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="completed keyexchange" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="completed challenge" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=info msg="authenticated AP as carsten.goldmann" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=info msg="authenticated Login5 as carsten.goldmann" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="stored credentials for carsten.goldmann" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="initializing zeroconf session, username: carsten.goldmann" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="dealer connection opened" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=trace msg="starting accesspoint recv loop" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=trace msg="starting dealer recv loop" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=trace msg="received accesspoint ping" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="received connection id: NDE3ODg4YzctNWM1Zi00NWMyLWIwODYtOGRiNzI3NTBjZTQ3K2RlYWxlcit0Y3A6Ly8wYWNhNTg0ZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNTYzNDUxNUE5QkZBNjc3MTYyNUIyN0YwOUVBNjAyOTk5QzYxMDkyMzA2MjQ5MTJFMUFFODQ3MjI3NzREMTFFRg==" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=trace msg="received accesspoint pong ack" Mar 15 12:25:21 hifipi go-librespot[3767]: time="2025-03-15T12:25:21+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 15 12:25:22 hifipi volumio[914]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Mar 15 12:25:22 hifipi volumio[914]: info: Sending Spotify command with payload to local API: /player/volume Mar 15 12:25:22 hifipi go-librespot[3767]: time="2025-03-15T12:25:22+01:00" level=debug msg="update volume to 56360/65535" Mar 15 12:25:22 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 15 12:25:22 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 12:25:22 hifipi volumio[914]: info: Received Get System Info Mar 15 12:25:22 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 12:25:22 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 12:25:22 hifipi volumio[914]: info: Discovery: Getting this device information Mar 15 12:25:22 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:22 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:22 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 12:25:23 hifipi go-librespot[3767]: time="2025-03-15T12:25:23+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 15 12:25:23 hifipi go-librespot[3767]: time="2025-03-15T12:25:23+01:00" level=trace msg="emitting websocket event: volume" Mar 15 12:25:24 hifipi volumio[914]: info: Initializing connection to go-librespot Websocket Mar 15 12:25:24 hifipi go-librespot[3767]: time="2025-03-15T12:25:24+01:00" level=debug msg="new websocket client" Mar 15 12:25:24 hifipi volumio[914]: info: Connection to go-librespot Websocket established Mar 15 12:25:24 hifipi volumio[914]: info: go-librespot daemon successfully initialized Mar 15 12:25:24 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 12:25:24 hifipi volumio[914]: info: Received Get System Info Mar 15 12:25:24 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 12:25:24 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 12:25:24 hifipi volumio[914]: info: Discovery: Getting this device information Mar 15 12:25:24 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:24 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:24 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 12:25:27 hifipi volumio[914]: info: Getting Spotify volume Mar 15 12:25:27 hifipi volumio[914]: info: Spotify volume: 86 Mar 15 12:25:27 hifipi volumio[914]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 15 12:25:27 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:27 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:27 hifipi volumio[914]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 15 12:25:27 hifipi volumio[914]: info: Initializing connection to go-librespot Websocket Mar 15 12:25:27 hifipi go-librespot[3767]: time="2025-03-15T12:25:27+01:00" level=debug msg="new websocket client" Mar 15 12:25:27 hifipi volumio[914]: info: Connection to go-librespot Websocket established Mar 15 12:25:30 hifipi volumio[914]: info: Getting Spotify volume Mar 15 12:25:30 hifipi volumio[914]: info: Spotify volume: 86 Mar 15 12:25:30 hifipi volumio[914]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 15 12:25:30 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:30 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:30 hifipi volumio[914]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 15 12:25:30 hifipi volumio[914]: info: CoreCommandRouter::volumioGetState Mar 15 12:25:30 hifipi volumio[914]: info: CorePlayQueue::getTrack 0 Mar 15 12:25:31 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 12:25:32 hifipi volumio[914]: info: Retrieving Cloud Streaming UI Mar 15 12:25:32 hifipi volumio[914]: info: Getting Tidal Cloud Configuration Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 12:25:32 hifipi volumio[914]: info: Getting Qobuz Cloud Configuration Mar 15 12:25:32 hifipi volumio[914]: info: Asking plugin for UI Config Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 12:25:32 hifipi volumio[914]: info: Getting Spotify Cloud Configuration Mar 15 12:25:32 hifipi volumio[914]: info: Asking plugin for UI Config Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 12:25:32 hifipi volumio[914]: info: Saving Spotify Acccount Mar 15 12:25:32 hifipi volumio[914]: info: Got Tidal Cloud Configuration Mar 15 12:25:32 hifipi volumio[914]: info: Got it Mar 15 12:25:32 hifipi volumio[914]: info: Got it Mar 15 12:25:32 hifipi volumio[914]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 12:25:32 hifipi volumio[914]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 12:25:32 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 15 12:25:35 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 15 12:25:35 hifipi volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 15 12:25:35 hifipi volumio[914]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 12:25:35 hifipi volumio[914]: Error: dns service error: unknown Mar 15 12:25:35 hifipi volumio[914]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Mar 15 12:25:35 hifipi volumio[914]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Mar 15 12:25:35 hifipi volumio[914]: errorCode: -65537 Mar 15 12:25:35 hifipi volumio[914]: } Mar 15 12:25:35 hifipi volumio[914]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 12:25:36 hifipi sudo[3811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-15 12:24 Mar 15 12:25:36 hifipi sudo[3811]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"