-- Logs begin at Sat 2026-01-10 18:08:07 IST, end at Sat 2026-01-10 22:00:47 IST. -- Jan 10 21:59:03 volumio volumio[998]: info: MyVolumio status changed Jan 10 21:59:03 volumio volumio[998]: info: Streaming services startup Jan 10 21:59:03 volumio volumio[998]: info: Starting Streaming Daemon Jan 10 21:59:03 volumio volumio[998]: info: Removing browser output: myVolumio user plan is not superstar Jan 10 21:59:03 volumio volumio[998]: info: Removing audio output: Jan 10 21:59:03 volumio volumio[998]: info: Starting Tunnel 2 Jan 10 21:59:03 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 10 21:59:03 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:59:03 volumio sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop tunnel.service Jan 10 21:59:03 volumio sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 21:59:03 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root Jan 10 21:59:03 volumio volumio[998]: error: Cannot start Volumio Streaming Daemon Jan 10 21:59:03 volumio volumio[998]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 10 21:59:03 volumio volumio[998]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 10 21:59:03 volumio sudo[1439]: pam_unix(sudo:session): session closed for user root Jan 10 21:59:03 volumio volumio[998]: error: Cannot stop Remote Daemon: Error: Command failed: /usr/bin/sudo /bin/systemctl stop tunnel.service Jan 10 21:59:03 volumio volumio[998]: Failed to stop tunnel.service: Unit tunnel.service not loaded. Jan 10 21:59:05 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 21:59:05 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:59:05 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 10 21:59:05 volumio volumio-remote-updater[568]: No test mode Jan 10 21:59:05 volumio volumio-remote-updater[568]: No alpha test mode Jan 10 21:59:05 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 10 21:59:05 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 21:59:05 volumio volumio[998]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 10 21:59:05 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 21:59:05 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 21:59:05 volumio volumio[998]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 10 21:59:05 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 10 21:59:07 volumio ntpd[815]: Soliciting pool server 192.46.211.253 Jan 10 21:59:08 volumio volumio[998]: info: Setting Geolocation for MyVolumio to as2 Jan 10 21:59:08 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:59:08 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:59:08 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:59:08 volumio ntpd[815]: Soliciting pool server 160.250.111.199 Jan 10 21:59:09 volumio ntpd[815]: Soliciting pool server 192.46.210.39 Jan 10 21:59:09 volumio volumio[998]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 10 21:59:09 volumio volumio[998]: info: Updating MyVolumio device info Jan 10 21:59:09 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:59:09 volumio ntpd[815]: Soliciting pool server 15.207.248.194 Jan 10 21:59:09 volumio ntpd[815]: Soliciting pool server 217.217.249.232 Jan 10 21:59:10 volumio ntpd[815]: Soliciting pool server 162.159.200.1 Jan 10 21:59:10 volumio volumio[998]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 10 21:59:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:59:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:59:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 10 21:59:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 10 21:59:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 10 21:59:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 10 21:59:10 volumio volumio[998]: info: CoreCommandRouter::volumioGetBrowseSources Jan 10 21:59:10 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 10 21:59:10 volumio ntpd[815]: Soliciting pool server 103.136.36.100 Jan 10 21:59:10 volumio ntpd[815]: Soliciting pool server 103.250.184.52 Jan 10 21:59:10 volumio ntpd[815]: Soliciting pool server 162.159.200.123 Jan 10 21:59:11 volumio ntpd[815]: Soliciting pool server 172.236.180.15 Jan 10 21:59:11 volumio ntpd[815]: Soliciting pool server 3.6.43.90 Jan 10 21:59:11 volumio ntpd[815]: Soliciting pool server 13.126.27.131 Jan 10 21:59:12 volumio volumio[998]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 10 21:59:12 volumio volumio[998]: info: Completed starting MyVolumio Plugin Jan 10 21:59:12 volumio volumio[998]: [Metrics] CommandRouter: 49s 124.17ms Jan 10 21:59:12 volumio volumio[998]: info: CoreCommandRouter::volumiosetStartupVolume Jan 10 21:59:12 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 21:59:12 volumio volumio[998]: info: VolumeController:: Setting startup Volume 35 Jan 10 21:59:12 volumio volumio[998]: info: VolumeController::SetAlsaVolume35 Jan 10 21:59:12 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:59:12 volumio volumio[998]: info: CoreCommandRouter::Close All Modals sent Jan 10 21:59:12 volumio volumio[998]: info: CoreCommandRouter::Close All Modals sent Jan 10 21:59:12 volumio volumio[998]: info: CoreStateMachine::pushState Jan 10 21:59:12 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 21:59:12 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 21:59:12 volumio volumio[998]: info: CoreCommandRouter::volumioPushState Jan 10 21:59:12 volumio volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jan 10 21:59:12 volumio volumio[998]: SPOTIFY: SPOTIFY VOLUME 22 Jan 10 21:59:12 volumio volumio[998]: SPOTIFY: VOLUMIO VOLUME 35 Jan 10 21:59:12 volumio volumio[998]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 10 21:59:12 volumio volumio[998]: info: Setting Spotify Volume from Volumio: 35 Jan 10 21:59:12 volumio ntpd[815]: Soliciting pool server 103.82.208.166 Jan 10 21:59:12 volumio ntpd[815]: Soliciting pool server 95.216.144.226 Jan 10 21:59:13 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 10 21:59:13 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 10 21:59:13 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 10 21:59:13 volumio volumio[998]: info: MYVOLUMIO: Adding device Jan 10 21:59:13 volumio volumio[998]: info: MYVOLUMIO: Evaluating Server Jan 10 21:59:13 volumio ntpd[815]: Soliciting pool server 2400:8904::f03c:92ff:fe80:f6f4 Jan 10 21:59:14 volumio volumio[998]: SPOTIFY: SETTING SPOTIFY VOLUME 35 Jan 10 21:59:14 volumio volumio[998]: info: Sending Spotify command with payload to local API: /player/volume Jan 10 21:59:14 volumio volumio[998]: info: Setting Geolocation for MyVolumio to as2 Jan 10 21:59:14 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:59:14 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:59:15 volumio volumio[998]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 10 21:59:15 volumio volumio[998]: info: Updating MyVolumio device info Jan 10 21:59:15 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 21:59:17 volumio ntpd[815]: receive: Unexpected origin timestamp 0xed0cfcdc.f531f213 does not match aorg 0000000000.00000000 from server@192.46.210.39 xmt 0xed0cfcdd.883ad68a Jan 10 21:59:17 volumio ntpd[815]: receive: Unexpected origin timestamp 0xed0cfcdc.f52ea11d does not match aorg 0000000000.00000000 from server@3.6.43.90 xmt 0xed0cfcdd.84fcbbd7 Jan 10 21:59:17 volumio volumio[998]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 10 21:59:19 volumio volumio[998]: info: BOOT COMPLETED Jan 10 21:59:32 volumio volumio[1304]: .............................................................................................................................................................................................................................................................................................................................................................++++ Jan 10 21:59:32 volumio volumio[1304]: e is 65537 (0x010001) Jan 10 21:59:32 volumio volumio[1304]: writing RSA key Jan 10 22:00:03 volumio go-librespot[1254]: time="2026-01-10T22:00:03+05:30" level=debug msg="obtained new client token: AAD1876Q5IL84Tou2E6vOgfw+sfEmAQ+rwfMblsGefMciWhvCb8O7yhO5/DHl7JcW5OK8MsmLNYrTh+mCI2uRdPVfrW9XmnavimrjYPLISM8o0xdiSG8cqOCMA0ag+osgYJN2aA8+203G8SaNr8D49fra6pxTF9dtcUnUuRbjGLTQ75TLgwBClMsiXwqmvBz/dM7SrQ7g45uEtnkSN74S5xud1es4qDBYMN4pB91ilBYDaSuYBSmfAeB" Jan 10 22:00:03 volumio go-librespot[1254]: time="2026-01-10T22:00:03+05:30" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 10 22:00:03 volumio go-librespot[1254]: time="2026-01-10T22:00:03+05:30" level=debug msg="completed keyexchange" Jan 10 22:00:03 volumio go-librespot[1254]: time="2026-01-10T22:00:03+05:30" level=debug msg="completed challenge" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=info msg="authenticated AP" username="31************************3e" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=info msg="authenticated Login5" username="31************************3e" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=info msg="accepted zeroconf from SM-M115F" username="31************************3e" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=debug msg="dealer connection opened" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=trace msg="starting accesspoint recv loop" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=trace msg="starting dealer recv loop" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=trace msg="received accesspoint ping" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=debug msg="received connection id: NzY0MzA5MmYtMzhi...NjA5NUNBQTI0Ng==" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=trace msg="received accesspoint pong ack" Jan 10 22:00:04 volumio go-librespot[1254]: time="2026-01-10T22:00:04+05:30" level=debug msg="put connect state because NEW_DEVICE" Jan 10 22:00:05 volumio go-librespot[1254]: time="2026-01-10T22:00:05+05:30" level=debug msg="handling transfer player command from 99669094898b18acd86467f33ac746b3af3ae414" Jan 10 22:00:05 volumio go-librespot[1254]: time="2026-01-10T22:00:05+05:30" level=debug msg="resolved context of track" uri="spotify:album:21Ka0v1wOGiJklCSmDGjDo" Jan 10 22:00:05 volumio go-librespot[1254]: time="2026-01-10T22:00:05+05:30" level=trace msg="fetched new page 0 with 5 items (list: 5)" uri="spotify:album:21Ka0v1wOGiJklCSmDGjDo" Jan 10 22:00:05 volumio go-librespot[1254]: time="2026-01-10T22:00:05+05:30" level=debug msg="loading track (paused: false, position: 3101ms)" uri="spotify:track:3YhgUyKWFApzsV1glDnMRO" Jan 10 22:00:05 volumio go-librespot[1254]: time="2026-01-10T22:00:05+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 22:00:05 volumio go-librespot[1254]: time="2026-01-10T22:00:05+05:30" level=trace msg="emitting websocket event: will_play" Jan 10 22:00:05 volumio volumio[998]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:album:21Ka0v1wOGiJklCSmDGjDo","uri":"spotify:track:3YhgUyKWFApzsV1glDnMRO","play_origin":""}} Jan 10 22:00:05 volumio go-librespot[1254]: time="2026-01-10T22:00:05+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1292" Jan 10 22:00:05 volumio go-librespot[1254]: time="2026-01-10T22:00:05+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Jan 10 22:00:06 volumio go-librespot[1254]: time="2026-01-10T22:00:06+05:30" level=debug msg="selected format OGG_VORBIS_320 (5560e06372ce1c08cc159b2fd3e948b3a2f42993)" uri="spotify:track:3YhgUyKWFApzsV1glDnMRO" Jan 10 22:00:06 volumio go-librespot[1254]: time="2026-01-10T22:00:06+05:30" level=debug msg="requested aes key for file 5560e06372ce1c08cc159b2fd3e948b3a2f42993, gid: 3YhgUyKWFApzsV1glDnMRO" Jan 10 22:00:06 volumio go-librespot[1254]: time="2026-01-10T22:00:06+05:30" level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed creating stream for spotify:track:3YhgUyKWFApzsV1glDnMRO: failed retrieving audio key: failed retrieving aes key with code 1" Jan 10 22:00:06 volumio go-librespot[1254]: time="2026-01-10T22:00:06+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 10 22:00:06 volumio go-librespot[1254]: time="2026-01-10T22:00:06+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 10 22:00:19 volumio volumio[998]: verbose: New Socket.io Connection to 192.168.29.203:3000 from 192.168.29.59 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 10 22:00:19 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 22:00:19 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 22:00:19 volumio volumio[998]: info: Discovery: Getting this device information Jan 10 22:00:19 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:19 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:19 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:20 volumio volumio[998]: verbose: New Socket.io Connection to 192.168.29.203:3000 from 192.168.29.59 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 22:00:20 volumio volumio[998]: info: Discovery: Getting this device information Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:20 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 22:00:20 volumio volumio[998]: info: Discovery: Getting this device information Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:20 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 10 22:00:20 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 10 22:00:20 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:20 volumio sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 10 22:00:20 volumio sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:20 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:20 volumio sudo[1648]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:20 volumio sudo[1656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 10 22:00:20 volumio sudo[1656]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:20 volumio sudo[1656]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:20 volumio sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 10 22:00:20 volumio sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:20 volumio sudo[1662]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:20 volumio sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 22:00:20 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:20 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:20 volumio sudo[1671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 22:00:20 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:20 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 22:00:20 volumio volumio[998]: info: Discovery: Getting this device information Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:20 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:20 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:21 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 22:00:21 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 22:00:21 volumio volumio[998]: info: Discovery: Getting this device information Jan 10 22:00:21 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:21 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:21 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:21 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:21 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:21 volumio volumio[998]: verbose: New Socket.io Connection to 192.168.29.203:3000 from 192.168.29.59 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 10 22:00:21 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 22:00:21 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 22:00:22 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 22:00:22 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 22:00:27 volumio sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 22:00:27 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:27 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:27 volumio sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 22:00:27 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:27 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:27 volumio volumio[998]: verbose: New Socket.io Connection to 192.168.29.203 from 192.168.29.59 UA: Mozilla/5.0 (Linux; Android 12; SM-M115F Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 10 22:00:27 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 10 22:00:28 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 10 22:00:28 volumio sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 22:00:28 volumio sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:28 volumio sudo[1698]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:28 volumio sudo[1700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 22:00:28 volumio sudo[1700]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:28 volumio sudo[1700]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:28 volumio volumio[998]: verbose: New Socket.io Connection to 192.168.29.203 from 192.168.29.59 UA: Mozilla/5.0 (Linux; Android 12; SM-M115F Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 10 22:00:29 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:30 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 10 22:00:30 volumio volumio[998]: info: Received Get System Info Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 22:00:30 volumio volumio[998]: info: Discovery: Getting this device information Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:30 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:30 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:30 volumio volumio[998]: info: Listing playlists Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 22:00:30 volumio volumio[998]: info: Received Get System Info Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 22:00:30 volumio volumio[998]: info: Discovery: Getting this device information Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:30 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:30 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 10 22:00:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 22:00:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 22:00:31 volumio volumio[998]: info: Discovery: Getting this device information Jan 10 22:00:31 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:31 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:31 volumio volumio[998]: verbose: New Socket.io Connection to 192.168.29.203:3000 from 192.168.29.59 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jan 10 22:00:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 10 22:00:31 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 10 22:00:32 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 10 22:00:34 volumio go-librespot[1254]: time="2026-01-10T22:00:34+05:30" level=trace msg="sent dealer ping" Jan 10 22:00:34 volumio go-librespot[1254]: time="2026-01-10T22:00:34+05:30" level=trace msg="received dealer pong" Jan 10 22:00:34 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 22:00:34 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 10 22:00:36 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 10 22:00:36 volumio volumio[998]: info: Received Get System Info Jan 10 22:00:36 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 22:00:36 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 22:00:36 volumio volumio[998]: info: Discovery: Getting this device information Jan 10 22:00:36 volumio volumio[998]: info: CoreCommandRouter::volumioGetState Jan 10 22:00:36 volumio volumio[998]: info: CorePlayQueue::getTrack 0 Jan 10 22:00:36 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:38 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 10 22:00:43 volumio volumio[998]: info: Enabling plugin fusiondsp Jan 10 22:00:43 volumio volumio[998]: info: Loading plugin "fusiondsp"... Jan 10 22:00:44 volumio volumio[998]: info: Preparing to generate the ALSA configuration file Jan 10 22:00:44 volumio volumio[998]: info: Asound.conf file unchanged, so no further update is needed Jan 10 22:00:44 volumio volumio[998]: info: Output device has changed, restarting MPD Jan 10 22:00:44 volumio sudo[1735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 22:00:44 volumio volumio[998]: info: PLUGIN START: fusiondsp Jan 10 22:00:44 volumio sudo[1735]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:44 volumio sudo[1735]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:44 volumio volumio[998]: info: Loading i18n strings for locale en Jan 10 22:00:44 volumio sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 22:00:44 volumio sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: FusionDsp - mixtype--------------------- Hardware Jan 10 22:00:44 volumio volumio[998]: info: Preparing to generate the ALSA configuration file Jan 10 22:00:44 volumio systemd[1]: Stopping Music Player Daemon... Jan 10 22:00:44 volumio volumio[998]: info: Done. Jan 10 22:00:44 volumio volumio[998]: info: MPD Permissions set Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: Asound.conf file unchanged, so no further update is needed Jan 10 22:00:44 volumio volumio[998]: info: Output device has changed, restarting MPD Jan 10 22:00:44 volumio sudo[1745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 10 22:00:44 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio sudo[1747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 10 22:00:44 volumio sudo[1747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:44 volumio volumio[998]: info: MPD Permissions set Jan 10 22:00:44 volumio volumio[998]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 22:00:44 volumio systemd[1]: mpd.service: Succeeded. Jan 10 22:00:44 volumio systemd[1]: Stopped Music Player Daemon. Jan 10 22:00:44 volumio volumio[998]: info: FusionDsp - Jan 10 22:00:44 volumio systemd[1]: Starting Music Player Daemon... Jan 10 22:00:44 volumio sudo[1754]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 10 22:00:44 volumio sudo[1754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:44 volumio sudo[1754]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:45 volumio volumio[998]: info: FusionDsp - undefined Jan 10 22:00:46 volumio mpd[1756]: Jan 10 22:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 10 22:00:46 volumio systemd[1]: Started Music Player Daemon. Jan 10 22:00:46 volumio sudo[1737]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:46 volumio sudo[1747]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:46 volumio volumio[998]: info: camilladsp service started and running in background, instance 1 Jan 10 22:00:46 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 10 22:00:46 volumio volumio[998]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 10 22:00:46 volumio volumio[998]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 10 22:00:46 volumio volumio[998]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 10 22:00:46 volumio volumio[998]: info: FusionDsp loaded Jan 10 22:00:46 volumio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 22:00:46 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 10 22:00:46 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 10 22:00:46 volumio volumio[998]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 10 22:00:46 volumio volumio[998]: info: Adding Signal Path Element [object Object] Jan 10 22:00:46 volumio volumio[998]: info: Adding fusiondspeq DSP Signal Path Element Jan 10 22:00:46 volumio volumio[998]: info: FusionDsp - ---- installed callbackRead Jan 10 22:00:46 volumio volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 22:00:46 volumio volumio[998]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jan 10 22:00:46 volumio volumio[998]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jan 10 22:00:46 volumio volumio[998]: at onErrorNT (internal/child_process.js:465:16) Jan 10 22:00:46 volumio volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jan 10 22:00:46 volumio volumio[998]: errno: -2, Jan 10 22:00:46 volumio volumio[998]: code: 'ENOENT', Jan 10 22:00:46 volumio volumio[998]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jan 10 22:00:46 volumio volumio[998]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jan 10 22:00:46 volumio volumio[998]: spawnargs: [ Jan 10 22:00:46 volumio volumio[998]: '-p', Jan 10 22:00:46 volumio volumio[998]: 9876, Jan 10 22:00:46 volumio volumio[998]: '-o', Jan 10 22:00:46 volumio volumio[998]: '/tmp/camilladsp.log', Jan 10 22:00:46 volumio volumio[998]: '-l', Jan 10 22:00:46 volumio volumio[998]: 'warn', Jan 10 22:00:46 volumio volumio[998]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jan 10 22:00:46 volumio volumio[998]: ] Jan 10 22:00:46 volumio volumio[998]: } Jan 10 22:00:46 volumio volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 22:00:46 volumio systemd[1]: Started FusionDsp Daemon. Jan 10 22:00:46 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root Jan 10 22:00:47 volumio volumio[1771]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jan 10 22:00:47 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jan 10 22:00:47 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 10 22:00:47 volumio sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-10 21:59 Jan 10 22:00:47 volumio sudo[1781]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"