-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2026-04-14 21:59:05 CEST. -- Apr 14 21:58:01 volumio ntpd[765]: Soliciting pool server 51.38.131.245 Apr 14 21:58:02 volumio ntpd[765]: Soliciting pool server 94.154.96.7 Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=debug msg="dealer connection opened" Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=trace msg="starting accesspoint recv loop" Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=trace msg="starting dealer recv loop" Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=trace msg="received accesspoint ping" Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=debug msg="received connection id: OGQzMmQ0MWMtMGRh...NUYzRUZCNTdFQw==" Apr 14 21:58:03 volumio volumio[979]: info: Spotify volume: 100 Apr 14 21:58:03 volumio go-librespot[1303]: time="2026-04-14T21:58:03+02:00" level=trace msg="received accesspoint pong ack" Apr 14 21:58:03 volumio ntpd[765]: Soliciting pool server 89.161.47.131 Apr 14 21:58:04 volumio ntpd[765]: Soliciting pool server 194.92.94.32 Apr 14 21:58:05 volumio volumio-remote-updater[579]: No test mode Apr 14 21:58:05 volumio volumio-remote-updater[579]: No alpha test mode Apr 14 21:58:05 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 14 21:58:06 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 21:58:06 volumio ntpd[765]: Soliciting pool server 162.159.200.1 Apr 14 21:58:07 volumio ntpd[765]: Soliciting pool server 178.215.228.24 Apr 14 21:58:08 volumio go-librespot[1303]: time="2026-04-14T21:58:08+02:00" level=debug msg="put connect state because NEW_DEVICE" Apr 14 21:58:08 volumio go-librespot[1303]: time="2026-04-14T21:58:08+02:00" level=debug msg="update volume requested to 65535/65535" Apr 14 21:58:08 volumio go-librespot[1303]: time="2026-04-14T21:58:08+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 14 21:58:08 volumio go-librespot[1303]: time="2026-04-14T21:58:08+02:00" level=trace msg="emitting websocket event: volume" Apr 14 21:58:08 volumio volumio[979]: info: Setting Volumio Volume from Spotify: 100 Apr 14 21:58:08 volumio volumio[979]: info: VolumeController::SetAlsaVolume100 Apr 14 21:58:08 volumio volumio[979]: info: CoreStateMachine::pushState Apr 14 21:58:08 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:08 volumio volumio[979]: info: CoreCommandRouter::volumioPushState Apr 14 21:58:13 volumio ntpd[765]: receive: Unexpected origin timestamp 0xed891ad0.8abed1bc does not match aorg 0000000000.00000000 from server@194.92.94.32 xmt 0xed891ad5.2f1897dc Apr 14 21:58:13 volumio ntpd[765]: receive: Unexpected origin timestamp 0xed891ad0.8aba7316 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xed891ad5.2efdf10a Apr 14 21:58:13 volumio ntpd[765]: receive: Unexpected origin timestamp 0xed891ad0.8ac14970 does not match aorg 0000000000.00000000 from server@94.154.96.7 xmt 0xed891ad5.2f68b895 Apr 14 21:58:13 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 21:58:14 volumio volumio[979]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 14 21:58:14 volumio volumio[979]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Apr 14 21:58:14 volumio volumio[979]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 14 21:58:14 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 14 21:58:15 volumio sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 21:58:15 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:58:15 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root Apr 14 21:58:15 volumio sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 21:58:15 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:58:15 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root Apr 14 21:58:15 volumio volumio[979]: verbose: New Socket.io Connection to 10.10.1.65 from 10.10.1.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:149.0) Gecko/20100101 Firefox/149.0 Engine version: 3 Transport: polling Total Clients: 7 Apr 14 21:58:15 volumio sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 21:58:15 volumio sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:58:15 volumio sudo[1492]: pam_unix(sudo:session): session closed for user root Apr 14 21:58:15 volumio sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 21:58:15 volumio sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:58:15 volumio sudo[1494]: pam_unix(sudo:session): session closed for user root Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: Received Get System Info Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 21:58:15 volumio volumio[979]: info: Discovery: Getting this device information Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 21:58:15 volumio volumio[979]: info: Received Get System Info Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 21:58:15 volumio volumio[979]: info: Discovery: Getting this device information Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: Listing playlists Apr 14 21:58:15 volumio volumio[979]: verbose: New Socket.io Connection to 10.10.1.65 from 10.10.1.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:149.0) Gecko/20100101 Firefox/149.0 Engine version: 3 Transport: polling Total Clients: 7 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: Received Get System Info Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 21:58:15 volumio volumio[979]: info: Discovery: Getting this device information Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 21:58:15 volumio volumio[979]: info: Received Get System Info Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 21:58:15 volumio volumio[979]: info: Discovery: Getting this device information Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:15 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:15 volumio volumio[979]: info: Listing playlists Apr 14 21:58:15 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 21:58:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 21:58:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 21:58:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 14 21:58:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 14 21:58:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 21:58:16 volumio volumio[979]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 21:58:16 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 21:58:16 volumio ntpd[765]: Soliciting pool server 51.68.141.5 Apr 14 21:58:17 volumio ntpd[765]: Soliciting pool server 176.32.165.145 Apr 14 21:58:17 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 21:58:17 volumio volumio[979]: info: Received Get System Info Apr 14 21:58:17 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 21:58:17 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 21:58:17 volumio volumio[979]: info: Discovery: Getting this device information Apr 14 21:58:17 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:17 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:17 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 21:58:18 volumio volumio[979]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 14 21:58:18 volumio volumio[979]: info: Completed starting MyVolumio Plugin Apr 14 21:58:18 volumio volumio[979]: [Metrics] CommandRouter: 60s 547.07ms Apr 14 21:58:18 volumio volumio[979]: info: CoreCommandRouter::volumiosetStartupVolume Apr 14 21:58:18 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 21:58:18 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 21:58:18 volumio volumio[979]: info: CoreCommandRouter::Close All Modals sent Apr 14 21:58:18 volumio volumio[979]: info: CoreCommandRouter::Close All Modals sent Apr 14 21:58:18 volumio ntpd[765]: Soliciting pool server 193.70.94.182 Apr 14 21:58:19 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 14 21:58:19 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 21:58:19 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 14 21:58:19 volumio ntpd[765]: Soliciting pool server 2a0d:5440::24 Apr 14 21:58:19 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:19 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:21 volumio ntpd[765]: Soliciting pool server 46.175.224.7 Apr 14 21:58:22 volumio ntpd[765]: Soliciting pool server 193.25.222.136 Apr 14 21:58:24 volumio volumio[979]: info: MyVolumio token set successfully Apr 14 21:58:24 volumio volumio[979]: info: MYVOLUMIO: Adding device Apr 14 21:58:24 volumio volumio[979]: info: MYVOLUMIO: Evaluating Server Apr 14 21:58:24 volumio volumio[979]: info: MyVolumio status changed Apr 14 21:58:24 volumio volumio[979]: info: Streaming services startup Apr 14 21:58:24 volumio volumio[979]: info: Starting Streaming Daemon Apr 14 21:58:25 volumio volumio[979]: info: Removing browser output: myVolumio user plan is not superstar Apr 14 21:58:25 volumio volumio[979]: info: Removing audio output: Apr 14 21:58:25 volumio volumio[979]: info: Stoppping Tunnel 1 Apr 14 21:58:25 volumio sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 14 21:58:25 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:58:25 volumio sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 14 21:58:25 volumio sudo[1536]: pam_unix(sudo:session): session closed for user root Apr 14 21:58:25 volumio volumio[979]: info: BOOT COMPLETED Apr 14 21:58:25 volumio volumio[979]: error: Cannot start Volumio Streaming Daemon Apr 14 21:58:25 volumio volumio[979]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 14 21:58:25 volumio volumio[979]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 14 21:58:25 volumio sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:58:25 volumio sudo[1539]: pam_unix(sudo:session): session closed for user root Apr 14 21:58:25 volumio volumio[979]: info: Remote SSH Stopped Apr 14 21:58:25 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 21:58:25 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 21:58:27 volumio sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 21:58:27 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:58:27 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root Apr 14 21:58:27 volumio sudo[1558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 21:58:27 volumio sudo[1558]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:58:27 volumio sudo[1558]: pam_unix(sudo:session): session closed for user root Apr 14 21:58:27 volumio volumio[979]: verbose: New Socket.io Connection to 10.10.1.65 from 10.10.1.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:149.0) Gecko/20100101 Firefox/149.0 Engine version: 3 Transport: polling Total Clients: 7 Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:27 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:27 volumio volumio[979]: info: Received Get System Info Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 21:58:27 volumio volumio[979]: info: Discovery: Getting this device information Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:27 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:27 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:27 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:27 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 21:58:27 volumio volumio[979]: info: Received Get System Info Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 21:58:27 volumio volumio[979]: info: Discovery: Getting this device information Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:27 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:27 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:27 volumio volumio[979]: info: Listing playlists Apr 14 21:58:27 volumio volumio[979]: info: Received Get System Info Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 21:58:27 volumio volumio[979]: info: Discovery: Getting this device information Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:27 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:27 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:27 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 21:58:28 volumio volumio[979]: info: CoreCommandRouter::volumioGetState Apr 14 21:58:28 volumio volumio[979]: info: CorePlayQueue::getTrack 0 Apr 14 21:58:30 volumio volumio[979]: info: Setting Geolocation for MyVolumio to eu4 Apr 14 21:58:30 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 21:58:30 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 21:58:30 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 21:58:31 volumio volumio[979]: info: Updating MyVolumio device info Apr 14 21:58:31 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 21:58:32 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 21:58:32 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 21:58:35 volumio volumio[979]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 14 21:58:36 volumio volumio[979]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 14 21:58:37 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 21:58:37 volumio go-librespot[1303]: time="2026-04-14T21:58:37+02:00" level=trace msg="sent dealer ping" Apr 14 21:58:37 volumio go-librespot[1303]: time="2026-04-14T21:58:37+02:00" level=trace msg="received dealer pong" Apr 14 21:58:38 volumio volumio[979]: info: Enabling plugin now_playing Apr 14 21:58:38 volumio volumio[979]: info: Loading plugin "now_playing"... Apr 14 21:58:38 volumio volumio[979]: error: !!!! WARNING !!!! Apr 14 21:58:38 volumio volumio[979]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Apr 14 21:58:38 volumio volumio[979]: Require stack: Apr 14 21:58:38 volumio volumio[979]: - /data/plugins/user_interface/now_playing/dist/index.js Apr 14 21:58:38 volumio volumio[979]: - /volumio/app/pluginmanager.js Apr 14 21:58:38 volumio volumio[979]: - /volumio/app/index.js Apr 14 21:58:38 volumio volumio[979]: - /volumio/index.js Apr 14 21:58:38 volumio volumio[979]: error: Stack trace: Error: Cannot find module 'geo-tz' Apr 14 21:58:38 volumio volumio[979]: Require stack: Apr 14 21:58:38 volumio volumio[979]: - /data/plugins/user_interface/now_playing/dist/index.js Apr 14 21:58:38 volumio volumio[979]: - /volumio/app/pluginmanager.js Apr 14 21:58:38 volumio volumio[979]: - /volumio/app/index.js Apr 14 21:58:38 volumio volumio[979]: - /volumio/index.js Apr 14 21:58:38 volumio volumio[979]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Apr 14 21:58:38 volumio volumio[979]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Apr 14 21:58:38 volumio volumio[979]: at Module.require (internal/modules/cjs/loader.js:952:19) Apr 14 21:58:38 volumio volumio[979]: at require (internal/modules/cjs/helpers.js:88:18) Apr 14 21:58:38 volumio volumio[979]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Apr 14 21:58:38 volumio volumio[979]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Apr 14 21:58:38 volumio volumio[979]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Apr 14 21:58:38 volumio volumio[979]: at Module.load (internal/modules/cjs/loader.js:928:32) Apr 14 21:58:38 volumio volumio[979]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Apr 14 21:58:38 volumio volumio[979]: at Module.require (internal/modules/cjs/loader.js:952:19) Apr 14 21:58:38 volumio volumio[979]: at require (internal/modules/cjs/helpers.js:88:18) Apr 14 21:58:38 volumio volumio[979]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 14 21:58:38 volumio volumio[979]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 14 21:58:38 volumio volumio[979]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 14 21:58:38 volumio volumio[979]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 14 21:58:38 volumio volumio[979]: error: !!!! WARNING !!!! Apr 14 21:58:38 volumio volumio[979]: info: Done. Apr 14 21:58:39 volumio volumio[979]: info: MYVOLUMIO: Adding device Apr 14 21:58:39 volumio volumio[979]: info: MYVOLUMIO: Evaluating Server Apr 14 21:58:45 volumio volumio[979]: info: Setting Geolocation for MyVolumio to eu4 Apr 14 21:58:45 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 21:58:46 volumio volumio[979]: info: Updating MyVolumio device info Apr 14 21:58:46 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 21:58:50 volumio volumio[979]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 21:58:52 volumio volumio[979]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 14 21:58:52 volumio volumio[979]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 14 21:58:56 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 21:58:56 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 21:59:02 volumio volumio[979]: info: Enabling plugin touch_display Apr 14 21:59:02 volumio volumio[979]: info: Loading plugin "touch_display"... Apr 14 21:59:02 volumio volumio[979]: info: Applying required configuration parameters for plugin touch_display Apr 14 21:59:02 volumio volumio[979]: info: PLUGIN START: touch_display Apr 14 21:59:02 volumio volumio[979]: info: Loading i18n strings for locale pl Apr 14 21:59:02 volumio volumio[979]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Apr 14 21:59:02 volumio volumio[979]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 21:59:03 volumio sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 14 21:59:03 volumio sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:59:03 volumio sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 14 21:59:03 volumio sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:59:03 volumio volumio[979]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Apr 14 21:59:03 volumio sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 14 21:59:03 volumio sudo[1665]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:59:03 volumio volumio[979]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Apr 14 21:59:03 volumio systemd[1]: Reloading. Apr 14 21:59:03 volumio sudo[1669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 14 21:59:03 volumio sudo[1669]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:59:03 volumio sudo[1669]: pam_unix(sudo:session): session closed for user root Apr 14 21:59:03 volumio volumio[979]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 14 21:59: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. Apr 14 21:59:03 volumio volumio[979]: info: Enabling plugin now_playing Apr 14 21:59:03 volumio volumio[979]: info: Loading plugin "now_playing"... Apr 14 21:59:03 volumio volumio[979]: error: !!!! WARNING !!!! Apr 14 21:59:03 volumio volumio[979]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Apr 14 21:59:03 volumio volumio[979]: Require stack: Apr 14 21:59:03 volumio volumio[979]: - /data/plugins/user_interface/now_playing/dist/index.js Apr 14 21:59:03 volumio volumio[979]: - /volumio/app/pluginmanager.js Apr 14 21:59:03 volumio volumio[979]: - /volumio/app/index.js Apr 14 21:59:03 volumio volumio[979]: - /volumio/index.js Apr 14 21:59:03 volumio volumio[979]: error: Stack trace: Error: Cannot find module 'geo-tz' Apr 14 21:59:03 volumio volumio[979]: Require stack: Apr 14 21:59:03 volumio volumio[979]: - /data/plugins/user_interface/now_playing/dist/index.js Apr 14 21:59:03 volumio volumio[979]: - /volumio/app/pluginmanager.js Apr 14 21:59:03 volumio volumio[979]: - /volumio/app/index.js Apr 14 21:59:03 volumio volumio[979]: - /volumio/index.js Apr 14 21:59:03 volumio volumio[979]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Apr 14 21:59:03 volumio volumio[979]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Apr 14 21:59:03 volumio volumio[979]: at Module.require (internal/modules/cjs/loader.js:952:19) Apr 14 21:59:03 volumio volumio[979]: at require (internal/modules/cjs/helpers.js:88:18) Apr 14 21:59:03 volumio volumio[979]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Apr 14 21:59:03 volumio volumio[979]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Apr 14 21:59:03 volumio volumio[979]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Apr 14 21:59:03 volumio volumio[979]: at Module.load (internal/modules/cjs/loader.js:928:32) Apr 14 21:59:03 volumio volumio[979]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Apr 14 21:59:03 volumio volumio[979]: at Module.require (internal/modules/cjs/loader.js:952:19) Apr 14 21:59:03 volumio volumio[979]: at require (internal/modules/cjs/helpers.js:88:18) Apr 14 21:59:03 volumio volumio[979]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Apr 14 21:59:03 volumio volumio[979]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 14 21:59:03 volumio volumio[979]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 14 21:59:03 volumio volumio[979]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 14 21:59:03 volumio volumio[979]: error: !!!! WARNING !!!! Apr 14 21:59:03 volumio volumio[979]: info: Error: TypeError: Cannot read property 'printToastMessage' of null Apr 14 21:59:03 volumio sudo[1665]: pam_unix(sudo:session): session closed for user root Apr 14 21:59:03 volumio volumio[979]: info: touch_display: systemctl daemon-reload succeeded. Apr 14 21:59:03 volumio systemd[1]: Reloading. Apr 14 21:59:03 volumio sudo[1660]: pam_unix(sudo:session): session closed for user root Apr 14 21:59:03 volumio volumio[979]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 14 21:59:03 volumio sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 14 21:59:03 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 21:59: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. Apr 14 21:59:04 volumio sudo[1662]: pam_unix(sudo:session): session closed for user root Apr 14 21:59:04 volumio volumio[979]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 14 21:59:04 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root Apr 14 21:59:04 volumio volumio[979]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Apr 14 21:59:04 volumio volumio[979]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Apr 14 21:59:04 volumio volumio[979]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 21:59:04 volumio volumio[979]: TypeError: Cannot read property 'printToastMessage' of null Apr 14 21:59:04 volumio volumio[979]: at /volumio/app/index.js:1214:32 Apr 14 21:59:04 volumio volumio[979]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Apr 14 21:59:04 volumio volumio[979]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Apr 14 21:59:04 volumio volumio[979]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1212:13) Apr 14 21:59:04 volumio volumio[979]: at /data/plugins/user_interface/touch_display/index.js:1732:26 Apr 14 21:59:04 volumio volumio[979]: at ChildProcess.exithandler (child_process.js:315:5) Apr 14 21:59:04 volumio volumio[979]: at ChildProcess.emit (events.js:315:20) Apr 14 21:59:04 volumio volumio[979]: at maybeClose (internal/child_process.js:1048:16) Apr 14 21:59:04 volumio volumio[979]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Apr 14 21:59:04 volumio volumio[979]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 21:59:05 volumio sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 21:58 Apr 14 21:59:05 volumio sudo[1709]: 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="5c0e72e4b5817fe66c91c9d15d75ff13b8e3f4ba" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 30 Mar 2026 02:51:29 PM CEST" VOLUMIO_VERSION="3.914" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="98fe12c88d8ff391f569279e2f874892"