-- Logs begin at Thu 2025-05-08 20:49:37 CEST, end at Sun 2025-05-11 19:18:10 CEST. -- May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 11 19:17:02 volumiodramper volumio[1242]: info: Adding plugin bluetooth to MyMusic Plugins May 11 19:17:02 volumiodramper volumio[1242]: info: Adding plugin multiroom to MyMusic Plugins May 11 19:17:02 volumiodramper volumio[1242]: info: Adding plugin metavolumio to MyMusic Plugins May 11 19:17:02 volumiodramper volumio[1242]: info: Adding plugin cd_controller to MyMusic Plugins May 11 19:17:02 volumiodramper volumio[1242]: info: Adding plugin smart_inputs to MyMusic Plugins May 11 19:17:02 volumiodramper volumio[1242]: info: Adding plugin tidalconnect to MyMusic Plugins May 11 19:17:02 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 11 19:17:03 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 11 19:17:03 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 11 19:17:03 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:03 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:03 volumiodramper volumio[1242]: info: Starting MyVolumio Remote Streaming Endpoints May 11 19:17:03 volumiodramper volumio[1242]: info: MyVolumio login type: Token May 11 19:17:03 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 11 19:17:03 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 11 19:17:04 volumiodramper volumio[1242]: info: Starting Streaming Service Transparent Proxy May 11 19:17:04 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 11 19:17:04 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 11 19:17:04 volumiodramper volumio[1242]: info: Streaming services startup May 11 19:17:04 volumiodramper volumio[1242]: info: Starting Streaming Daemon May 11 19:17:04 volumiodramper sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 11 19:17:04 volumiodramper sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:04 volumiodramper sudo[1526]: pam_unix(sudo:session): session closed for user root May 11 19:17:04 volumiodramper volumio[1242]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 11 19:17:04 volumiodramper volumio[1242]: info: Getting Spotify volume May 11 19:17:04 volumiodramper volumio[1242]: error: Cannot start Volumio Streaming Daemon May 11 19:17:04 volumiodramper volumio[1242]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 11 19:17:04 volumiodramper volumio[1242]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 11 19:17:04 volumiodramper volumio[1242]: info: Spotify volume: 100 May 11 19:17:04 volumiodramper volumio[1242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 11 19:17:04 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:04 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:04 volumiodramper volumio[1242]: STREAMING PROXY: Starting server on port 3245 May 11 19:17:04 volumiodramper volumio[1242]: Node JS runtime: 14 May 11 19:17:05 volumiodramper volumio[1242]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 11 19:17:05 volumiodramper volumio[1242]: info: MyVolumio token set successfully May 11 19:17:05 volumiodramper volumio[1242]: info: MYVOLUMIO: Adding device May 11 19:17:05 volumiodramper volumio[1242]: info: MYVOLUMIO: Evaluating Server May 11 19:17:06 volumiodramper volumio[1242]: info: MyVolumio status changed May 11 19:17:06 volumiodramper volumio[1242]: info: Streaming services startup May 11 19:17:06 volumiodramper volumio[1242]: info: Starting Streaming Daemon May 11 19:17:06 volumiodramper volumio[1242]: info: Removing browser output: myVolumio user plan is not superstar May 11 19:17:06 volumiodramper volumio[1242]: info: Removing audio output: May 11 19:17:06 volumiodramper volumio[1242]: info: Stoppping Tunnel 1 May 11 19:17:06 volumiodramper sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 11 19:17:06 volumiodramper sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:06 volumiodramper sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 11 19:17:06 volumiodramper sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:06 volumiodramper sudo[1554]: pam_unix(sudo:session): session closed for user root May 11 19:17:06 volumiodramper volumio[1242]: error: Cannot start Volumio Streaming Daemon May 11 19:17:06 volumiodramper volumio[1242]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 11 19:17:06 volumiodramper volumio[1242]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 11 19:17:06 volumiodramper sudo[1556]: pam_unix(sudo:session): session closed for user root May 11 19:17:06 volumiodramper volumio[1242]: info: Remote SSH Stopped May 11 19:17:06 volumiodramper volumio[1242]: info: Setting Geolocation for MyVolumio to eu4 May 11 19:17:06 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:06 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:06 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:06 volumiodramper volumio[1242]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 11 19:17:07 volumiodramper volumio[1242]: info: Updating MyVolumio device info May 11 19:17:07 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:07 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:07 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:07 volumiodramper volumio[1503]: ....................................................................................................++++ May 11 19:17:07 volumiodramper volumio[1242]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 11 19:17:10 volumiodramper volumio[1242]: info: MYVOLUMIO: Adding device May 11 19:17:10 volumiodramper volumio[1242]: info: MYVOLUMIO: Evaluating Server May 11 19:17:11 volumiodramper volumio[1242]: info: Setting Geolocation for MyVolumio to eu4 May 11 19:17:11 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:11 volumiodramper volumio[1242]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 11 19:17:12 volumiodramper systemd[1]: systemd-timedated.service: Succeeded. May 11 19:17:12 volumiodramper volumio[1242]: info: Updating MyVolumio device info May 11 19:17:12 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:12 volumiodramper volumio[1242]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 11 19:17:18 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 19:17:18 volumiodramper volumio[1242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 11 19:17:18 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:18 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetBrowseSources May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 19:17:23 volumiodramper volumio-remote-updater[664]: No test mode May 11 19:17:23 volumiodramper volumio-remote-updater[664]: No alpha test mode May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 11 19:17:23 volumiodramper volumio[1242]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

NEW ADDITIONS

 

","title":"Update v3.807","updateavailable":true} May 11 19:17:23 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 11 19:17:24 volumiodramper volumio[1242]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 11 19:17:24 volumiodramper volumio[1242]: info: Completed starting MyVolumio Plugin May 11 19:17:24 volumiodramper volumio[1242]: [Metrics] CommandRouter: 37s 900.49ms May 11 19:17:24 volumiodramper volumio[1242]: info: CoreCommandRouter::volumiosetStartupVolume May 11 19:17:24 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 19:17:24 volumiodramper volumio[1242]: info: VolumeController:: Setting startup Volume 10 May 11 19:17:24 volumiodramper volumio[1242]: info: VolumeController::SetAlsaVolume10 May 11 19:17:24 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:24 volumiodramper volumio[1242]: info: CoreCommandRouter::Close All Modals sent May 11 19:17:24 volumiodramper volumio[1242]: info: CoreCommandRouter::Close All Modals sent May 11 19:17:24 volumiodramper volumio[1242]: info: CoreStateMachine::pushState May 11 19:17:24 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:24 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 19:17:24 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioPushState May 11 19:17:24 volumiodramper volumio[1242]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 May 11 19:17:24 volumiodramper volumio[1242]: SPOTIFY: SPOTIFY VOLUME 100 May 11 19:17:24 volumiodramper volumio[1242]: SPOTIFY: VOLUMIO VOLUME 10 May 11 19:17:24 volumiodramper volumio[1242]: SPOTIFY: DELTA VOLUME ENOUGH: true May 11 19:17:24 volumiodramper volumio[1242]: info: Setting Spotify Volume from Volumio: 10 May 11 19:17:24 volumiodramper volumio[1242]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 11 19:17:24 volumiodramper volumio[1242]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 11 19:17:24 volumiodramper volumio[1242]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 11 19:17:24 volumiodramper volumio[1242]: aplay: main:828: audio open error: No such device May 11 19:17:24 volumiodramper go-librespot[1449]: time="2025-05-11T19:17:24+02:00" level=trace msg="sent dealer ping" May 11 19:17:24 volumiodramper go-librespot[1449]: time="2025-05-11T19:17:24+02:00" level=trace msg="received dealer pong" May 11 19:17:25 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 11 19:17:25 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 11 19:17:25 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 11 19:17:25 volumiodramper volumio[1242]: SPOTIFY: SETTING SPOTIFY VOLUME 10 May 11 19:17:25 volumiodramper volumio[1242]: info: Sending Spotify command with payload to local API: /player/volume May 11 19:17:25 volumiodramper go-librespot[1449]: time="2025-05-11T19:17:25+02:00" level=debug msg="update volume to 6553/65535" May 11 19:17:26 volumiodramper go-librespot[1449]: time="2025-05-11T19:17:26+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 11 19:17:26 volumiodramper go-librespot[1449]: time="2025-05-11T19:17:26+02:00" level=trace msg="emitting websocket event: volume" May 11 19:17:26 volumiodramper volumio[1242]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} May 11 19:17:26 volumiodramper volumio[1242]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 May 11 19:17:28 volumiodramper ntpd[909]: Soliciting pool server 192.86.14.67 May 11 19:17:29 volumiodramper ntpd[909]: Soliciting pool server 193.59.134.156 May 11 19:17:30 volumiodramper ntpd[909]: Soliciting pool server 176.32.165.145 May 11 19:17:30 volumiodramper ntpd[909]: Soliciting pool server 212.127.95.218 May 11 19:17:31 volumiodramper ntpd[909]: Soliciting pool server 212.127.78.21 May 11 19:17:31 volumiodramper ntpd[909]: Soliciting pool server 94.154.96.7 May 11 19:17:31 volumiodramper volumio[1242]: info: BOOT COMPLETED May 11 19:17:31 volumiodramper volumio[1242]: info: mpdhttpout ---Boot completed detected! Patching mpd now! May 11 19:17:31 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:31 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:31 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioPause May 11 19:17:31 volumiodramper volumio[1242]: info: CoreStateMachine::pause May 11 19:17:31 volumiodramper volumio[1242]: info: mpdhttpout --- Volumio set on pause May 11 19:17:31 volumiodramper volumio[1242]: info: mpdhttpout ---Configuration successfully added to mpd.conf. May 11 19:17:32 volumiodramper ntpd[909]: Soliciting pool server 185.157.13.96 May 11 19:17:32 volumiodramper ntpd[909]: Soliciting pool server 194.146.251.102 May 11 19:17:32 volumiodramper ntpd[909]: Soliciting pool server 193.25.222.136 May 11 19:17:33 volumiodramper ntpd[909]: Soliciting pool server 178.215.228.24 May 11 19:17:33 volumiodramper ntpd[909]: Soliciting pool server 193.70.94.182 May 11 19:17:33 volumiodramper ntpd[909]: Soliciting pool server 162.159.200.123 May 11 19:17:33 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 11 19:17:33 volumiodramper sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 11 19:17:33 volumiodramper sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:33 volumiodramper systemd[1]: Stopping Music Player Daemon... May 11 19:17:33 volumiodramper systemd[1]: mpd.service: Succeeded. May 11 19:17:33 volumiodramper systemd[1]: Stopped Music Player Daemon. May 11 19:17:33 volumiodramper systemd[1]: Starting Music Player Daemon... May 11 19:17:33 volumiodramper sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 19:17:33 volumiodramper sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:33 volumiodramper sudo[1670]: pam_unix(sudo:session): session closed for user root May 11 19:17:33 volumiodramper sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 19:17:33 volumiodramper sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:33 volumiodramper sudo[1672]: pam_unix(sudo:session): session closed for user root May 11 19:17:33 volumiodramper volumio[1242]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 11 19:17:33 volumiodramper sudo[1668]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 11 19:17:33 volumiodramper sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:33 volumiodramper sudo[1668]: pam_unix(sudo:session): session closed for user root May 11 19:17:34 volumiodramper ntpd[909]: Soliciting pool server 162.159.200.1 May 11 19:17:34 volumiodramper ntpd[909]: Soliciting pool server 213.222.217.11 May 11 19:17:34 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 19:17:34 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 11 19:17:34 volumiodramper mpd[1676]: May 11 19:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 11 19:17:34 volumiodramper systemd[1]: Started Music Player Daemon. May 11 19:17:34 volumiodramper sudo[1666]: pam_unix(sudo:session): session closed for user root May 11 19:17:34 volumiodramper volumio[1242]: error: updateQueue error: null May 11 19:17:35 volumiodramper ntpd[909]: Soliciting pool server 91.210.128.220 May 11 19:17:35 volumiodramper ntpd[909]: Soliciting pool server 51.68.141.5 May 11 19:17:35 volumiodramper ntpd[909]: receive: Unexpected origin timestamp 0xebcb5a2f.033a162a does not match aorg 0000000000.00000000 from server@213.222.217.11 xmt 0xebcb5a2f.a729439b May 11 19:17:35 volumiodramper ntpd[909]: receive: Unexpected origin timestamp 0xebcb5a2f.033f0546 does not match aorg 0000000000.00000000 from server@94.154.96.7 xmt 0xebcb5a2f.a78fa523 May 11 19:17:36 volumiodramper sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 19:17:36 volumiodramper sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:36 volumiodramper sudo[1680]: pam_unix(sudo:session): session closed for user root May 11 19:17:36 volumiodramper sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 19:17:36 volumiodramper sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:36 volumiodramper sudo[1682]: pam_unix(sudo:session): session closed for user root May 11 19:17:36 volumiodramper volumio[1242]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 11 19:17:36 volumiodramper ntpd[909]: Soliciting pool server 2a00:d5c0:10:10::136 May 11 19:17:36 volumiodramper sudo[1686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 11 19:17:36 volumiodramper sudo[1686]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:36 volumiodramper sudo[1686]: pam_unix(sudo:session): session closed for user root May 11 19:17:36 volumiodramper sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 11 19:17:36 volumiodramper sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 19:17:36 volumiodramper sudo[1688]: pam_unix(sudo:session): session closed for user root May 11 19:17:36 volumiodramper volumio[1242]: verbose: New Socket.io Connection to 192.168.1.123 from 192.168.1.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetVisibleSources May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:36 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 11 19:17:36 volumiodramper volumio[1242]: info: Received Get System Info May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 19:17:36 volumiodramper volumio[1242]: info: Discovery: Getting this device information May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:36 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 19:17:36 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:36 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:37 volumiodramper volumio[1242]: info: Listing playlists May 11 19:17:37 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 11 19:17:37 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 19:17:37 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 11 19:17:37 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:37 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:38 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 11 19:17:38 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 19:17:38 volumiodramper volumio[1242]: info: Received Get System Info May 11 19:17:38 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 19:17:38 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 19:17:38 volumiodramper volumio[1242]: info: Discovery: Getting this device information May 11 19:17:38 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:38 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:38 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 19:17:39 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 19:17:39 volumiodramper volumio[1242]: info: Received Get System Info May 11 19:17:39 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 19:17:39 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 19:17:39 volumiodramper volumio[1242]: info: Discovery: Getting this device information May 11 19:17:39 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:39 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:39 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 19:17:39 volumiodramper volumio[1503]: ..................................................................................................................................................................................................................................................................................................................................................................++++ May 11 19:17:39 volumiodramper volumio[1503]: e is 65537 (0x010001) May 11 19:17:39 volumiodramper volumio[1503]: writing RSA key May 11 19:17:41 volumiodramper volumio[1242]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 19:17:41 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 11 19:17:41 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:41 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 11 19:17:41 volumiodramper volumio[1242]: info: Getting Alsa Cards List without I2S DAC May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 19:17:42 volumiodramper volumio[1242]: Invalid card number. May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 11 19:17:42 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 11 19:17:46 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 11 19:17:54 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 11 19:17:54 volumiodramper volumio[1242]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 11 19:17:54 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 11 19:17:54 volumiodramper volumio[1242]: info: Received Get System Version May 11 19:17:54 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 19:17:54 volumiodramper volumio[1242]: info: Received Get System Info May 11 19:17:54 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 19:17:54 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 19:17:54 volumiodramper volumio[1242]: info: Discovery: Getting this device information May 11 19:17:54 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:17:54 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:17:54 volumiodramper volumio[1242]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 19:17:55 volumiodramper go-librespot[1449]: time="2025-05-11T19:17:55+02:00" level=trace msg="sent dealer ping" May 11 19:17:55 volumiodramper go-librespot[1449]: time="2025-05-11T19:17:55+02:00" level=trace msg="received dealer pong" May 11 19:18:05 volumiodramper volumio[1242]: info: CoreCommandRouter::volumioGetState May 11 19:18:05 volumiodramper volumio[1242]: info: CorePlayQueue::getTrack 0 May 11 19:18:10 volumiodramper volumio[1242]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ May 11 19:18:10 volumiodramper volumio[1242]: info: PLUGIN onShutdown : networkfs May 11 19:18:10 volumiodramper volumio[1242]: info: PLUGIN onShutdown : audiophonicsonoff May 11 19:18:10 volumiodramper volumio[1242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 19:18:10 volumiodramper volumio[1242]: TypeError: Cannot read property 'writeSync' of undefined May 11 19:18:10 volumiodramper volumio[1242]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) May 11 19:18:10 volumiodramper volumio[1242]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) May 11 19:18:10 volumiodramper volumio[1242]: at HashMap. (/volumio/app/pluginmanager.js:621:31) May 11 19:18:10 volumiodramper volumio[1242]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 11 19:18:10 volumiodramper volumio[1242]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 11 19:18:10 volumiodramper volumio[1242]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) May 11 19:18:10 volumiodramper volumio[1242]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) May 11 19:18:10 volumiodramper volumio[1242]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) May 11 19:18:10 volumiodramper volumio[1242]: at Socket.emit (events.js:315:20) May 11 19:18:10 volumiodramper volumio[1242]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 11 19:18:10 volumiodramper volumio[1242]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 11 19:18:10 volumiodramper volumio[1242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 19:18:10 volumiodramper sudo[1785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 19:17 May 11 19:18:10 volumiodramper sudo[1785]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"