Feb 08 03:06:00 volumio volumio[1786348]: info: go-librespot daemon successfully initialized Feb 08 03:06:02 volumio volumio[1786348]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 08 03:06:03 volumio volumio[1786348]: info: Initializing connection to go-librespot Websocket Feb 08 03:06:03 volumio go-librespot[1786524]: time="2026-02-08T03:06:03+07:00" level=debug msg="new websocket client" Feb 08 03:06:03 volumio volumio[1786348]: info: Connection to go-librespot Websocket established Feb 08 03:06:03 volumio go-librespot[1786524]: time="2026-02-08T03:06:03+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:03 volumio go-librespot[1786524]: time="2026-02-08T03:06:03+07:00" level=debug msg="completed challenge" Feb 08 03:06:03 volumio go-librespot[1786524]: time="2026-02-08T03:06:03+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:03 volumio sudo[1786603]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 03:06:03 volumio sudo[1786603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:03 volumio sudo[1786603]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:03 volumio sudo[1786604]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 03:06:03 volumio sudo[1786604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:03 volumio sudo[1786609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 08 03:06:03 volumio sudo[1786604]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:03 volumio sudo[1786609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:03 volumio sudo[1786609]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:04 volumio volumio[1786348]: info: Upmpdcli Daemon Started Feb 08 03:06:04 volumio go-librespot[1786524]: time="2026-02-08T03:06:04+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 08 03:06:04 volumio go-librespot[1786524]: time="2026-02-08T03:06:04+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:04 volumio go-librespot[1786524]: time="2026-02-08T03:06:04+07:00" level=debug msg="completed challenge" Feb 08 03:06:04 volumio go-librespot[1786524]: time="2026-02-08T03:06:04+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:04 volumio go-librespot[1786524]: time="2026-02-08T03:06:04+07:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 08 03:06:05 volumio go-librespot[1786524]: time="2026-02-08T03:06:05+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:05 volumio go-librespot[1786524]: time="2026-02-08T03:06:05+07:00" level=debug msg="completed challenge" Feb 08 03:06:05 volumio go-librespot[1786524]: time="2026-02-08T03:06:05+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 08 03:06:05 volumio volumio[1786348]: info: Adding plugin bluetooth to MyMusic Plugins Feb 08 03:06:05 volumio volumio[1786348]: info: Adding plugin multiroom to MyMusic Plugins Feb 08 03:06:05 volumio volumio[1786348]: info: Adding plugin metavolumio to MyMusic Plugins Feb 08 03:06:05 volumio volumio[1786348]: info: Adding plugin cd_controller to MyMusic Plugins Feb 08 03:06:05 volumio volumio[1786348]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 08 03:06:05 volumio volumio[1786348]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 08 03:06:05 volumio volumio[1786348]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 08 03:06:05 volumio volumio[1786348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:05 volumio volumio[1786348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:05 volumio volumio[1786348]: info: Starting MyVolumio Remote Streaming Endpoints Feb 08 03:06:05 volumio volumio[1786348]: info: MyVolumio login type: Token Feb 08 03:06:05 volumio go-librespot[1786524]: time="2026-02-08T03:06:05+07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 08 03:06:05 volumio volumio[1786348]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 08 03:06:06 volumio volumio[1786348]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 08 03:06:06 volumio volumio[1786348]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 08 03:06:06 volumio volumio[1786348]: info: Streaming services startup Feb 08 03:06:06 volumio volumio[1786348]: info: Starting Streaming Daemon Feb 08 03:06:06 volumio volumio[1786348]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 08 03:06:06 volumio sudo[1786616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 08 03:06:06 volumio sudo[1786616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:06 volumio sudo[1786616]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:06 volumio volumio[1786348]: info: Getting Spotify volume Feb 08 03:06:06 volumio volumio[1786348]: error: Cannot start Volumio Streaming Daemon Feb 08 03:06:06 volumio volumio[1786348]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 08 03:06:06 volumio volumio[1786348]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 08 03:06:06 volumio volumio[1786348]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 08 03:06:06 volumio volumio[1786348]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:06 volumio volumio[1786348]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:06 volumio volumio[1786348]: info: Listing playlists Feb 08 03:06:06 volumio volumio[1786348]: info: Listing playlists Feb 08 03:06:06 volumio volumio[1786348]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:06 volumio volumio[1786348]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:06 volumio go-librespot[1786524]: time="2026-02-08T03:06:06+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:06 volumio go-librespot[1786524]: time="2026-02-08T03:06:06+07:00" level=debug msg="completed challenge" Feb 08 03:06:06 volumio volumio[1786348]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Feb 08 03:06:06 volumio volumio[1786348]: SPOTIFY: SPOTIFY VOLUME undefined Feb 08 03:06:06 volumio volumio[1786348]: SPOTIFY: VOLUMIO VOLUME 48 Feb 08 03:06:06 volumio volumio[1786348]: info: Aligning Spotify Volume to Volumio Volume Feb 08 03:06:06 volumio volumio[1786348]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:06 volumio volumio[1786348]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:06 volumio volumio[1786348]: info: Setting Spotify Volume from Volumio: 48 Feb 08 03:06:06 volumio go-librespot[1786524]: time="2026-02-08T03:06:06+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:07 volumio go-librespot[1786524]: time="2026-02-08T03:06:07+07:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 08 03:06:07 volumio volumio[1786348]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 08 03:06:07 volumio nmbd[984]: [2026/02/08 03:06:07.615143, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 08 03:06:07 volumio nmbd[984]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.133 for name WORKGROUP<1d>. Feb 08 03:06:07 volumio nmbd[984]: This response was from IP 192.168.1.100, reporting an IP address of 192.168.1.100. Feb 08 03:06:07 volumio go-librespot[1786524]: time="2026-02-08T03:06:07+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:07 volumio go-librespot[1786524]: time="2026-02-08T03:06:07+07:00" level=debug msg="completed challenge" Feb 08 03:06:07 volumio go-librespot[1786524]: time="2026-02-08T03:06:07+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:07 volumio volumio[1786348]: info: MyVolumio token set successfully Feb 08 03:06:07 volumio volumio[1786348]: info: MYVOLUMIO: Adding device Feb 08 03:06:07 volumio volumio[1786348]: info: MYVOLUMIO: Evaluating Server Feb 08 03:06:07 volumio volumio[1786348]: SPOTIFY: SETTING SPOTIFY VOLUME 48 Feb 08 03:06:07 volumio volumio[1786348]: info: Sending Spotify command with payload to local API: /player/volume Feb 08 03:06:08 volumio volumio[1786348]: info: MyVolumio status changed Feb 08 03:06:08 volumio volumio[1786348]: info: Streaming services startup Feb 08 03:06:08 volumio volumio[1786348]: info: Starting Streaming Daemon Feb 08 03:06:08 volumio volumio[1786348]: info: Removing browser output: myVolumio user plan is not superstar Feb 08 03:06:08 volumio volumio[1786348]: info: Removing audio output: Feb 08 03:06:08 volumio volumio[1786348]: info: Stoppping Tunnel 1 Feb 08 03:06:08 volumio sudo[1786646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 08 03:06:08 volumio sudo[1786646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:08 volumio sudo[1786649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 08 03:06:08 volumio sudo[1786649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:08 volumio sudo[1786646]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:08 volumio volumio[1786348]: error: Cannot start Volumio Streaming Daemon Feb 08 03:06:08 volumio volumio[1786348]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 08 03:06:08 volumio volumio[1786348]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 08 03:06:08 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:06:08 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:06:08 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:06:08 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:06:08 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:06:08 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:06:08 volumio sudo[1786649]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:08 volumio volumio[1786348]: info: Remote SSH Stopped Feb 08 03:06:13 volumio go-librespot[1786524]: time="2026-02-08T03:06:13+07:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 08 03:06:13 volumio go-librespot[1786524]: time="2026-02-08T03:06:13+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:13 volumio go-librespot[1786524]: time="2026-02-08T03:06:13+07:00" level=debug msg="completed challenge" Feb 08 03:06:13 volumio volumio[1786348]: info: Setting Geolocation for MyVolumio to eu6 Feb 08 03:06:13 volumio volumio[1786348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:13 volumio volumio[1786348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:13 volumio volumio[1786348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:13 volumio go-librespot[1786524]: time="2026-02-08T03:06:13+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:13 volumio go-librespot[1786524]: time="2026-02-08T03:06:13+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:13 volumio volumio[1786348]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Feb 08 03:06:13 volumio volumio[1786348]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 03:06:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 03:06:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 03:06:13 volumio volumio[1786348]: Error: socket hang up Feb 08 03:06:13 volumio volumio[1786348]: at connResetException (node:internal/errors:720:14) Feb 08 03:06:13 volumio volumio[1786348]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 08 03:06:13 volumio volumio[1786348]: at Socket.emit (node:events:526:35) Feb 08 03:06:13 volumio volumio[1786348]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 08 03:06:13 volumio volumio[1786348]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 08 03:06:13 volumio volumio[1786348]: code: 'ECONNRESET', Feb 08 03:06:13 volumio volumio[1786348]: response: undefined Feb 08 03:06:13 volumio volumio[1786348]: } Feb 08 03:06:13 volumio volumio[1786348]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 03:06:13 volumio sudo[1786684]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 03:05' Feb 08 03:06:13 volumio sudo[1786684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:14 volumio sudo[1786684]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:14 volumio volumio-remote-updater[721]: [2026-02-08 03:06:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 08 03:06:14 volumio volumio-remote-updater[721]: [2026-02-08 03:06:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 08 03:06:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 08 03:06:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 08 03:06:14 volumio systemd[1]: volumio.service: Consumed 3.940s CPU time. Feb 08 03:06:14 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 03:06:14 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 03:06:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5042. Feb 08 03:06:14 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 03:06:14 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 08 03:06:14 volumio systemd[1]: volumio.service: Consumed 3.940s CPU time. Feb 08 03:06:14 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 08 03:06:14 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 03:06:15 volumio volumio[1786700]: info: ------------------------------------------- Feb 08 03:06:15 volumio volumio[1786700]: info: ----- Volumio3 ---- Feb 08 03:06:15 volumio volumio[1786700]: info: ------------------------------------------- Feb 08 03:06:15 volumio volumio[1786700]: info: ----- System startup ---- Feb 08 03:06:15 volumio volumio[1786700]: info: ------------------------------------------- Feb 08 03:06:15 volumio volumio[1786700]: info: MYVOLUMIO Environment detected Feb 08 03:06:15 volumio volumio[1786700]: info: Plugin folders cleanup Feb 08 03:06:15 volumio volumio[1786700]: info: Scanning into folder /volumio/app/plugins/ Feb 08 03:06:15 volumio volumio[1786700]: info: Scanning category audio_interface Feb 08 03:06:15 volumio volumio[1786700]: info: Scanning category miscellanea Feb 08 03:06:15 volumio volumio[1786700]: info: Scanning category music_service Feb 08 03:06:15 volumio volumio[1786700]: info: Scanning category plugins.json Feb 08 03:06:15 volumio volumio[1786700]: info: Scanning category system_controller Feb 08 03:06:15 volumio volumio[1786700]: info: Scanning category user_interface Feb 08 03:06:15 volumio volumio[1786700]: info: Scanning into folder /data/plugins/ Feb 08 03:06:15 volumio volumio[1786700]: info: Scanning category music_service Feb 08 03:06:15 volumio volumio[1786700]: info: Plugin folders cleanup completed Feb 08 03:06:15 volumio volumio[1786700]: info: ------------------------------------------- Feb 08 03:06:15 volumio volumio[1786700]: info: ----- Core plugins startup ---- Feb 08 03:06:15 volumio volumio[1786700]: info: ------------------------------------------- Feb 08 03:06:15 volumio volumio[1786700]: info: Loading plugins from folder /volumio/app/plugins/ Feb 08 03:06:15 volumio volumio[1786700]: info: Adding plugin upnp to MyMusic Plugins Feb 08 03:06:15 volumio volumio[1786700]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 08 03:06:15 volumio volumio[1786700]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 08 03:06:15 volumio volumio[1786700]: info: Loading plugins from folder /data/plugins/ Feb 08 03:06:15 volumio volumio[1786700]: info: Loading plugin "system"... Feb 08 03:06:15 volumio volumio[1786700]: info: Loading plugin "appearance"... Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "network"... Feb 08 03:06:16 volumio volumio[1786700]: info: Refreshing Cached IP Addresses Feb 08 03:06:16 volumio sudo[1786729]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "services"... Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "alsa_controller"... Feb 08 03:06:16 volumio sudo[1786729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:16 volumio sudo[1786738]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 08 03:06:16 volumio sudo[1786738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:16 volumio sudo[1786731]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 03:06:16 volumio sudo[1786729]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:16 volumio sudo[1786731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:16 volumio sudo[1786738]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:16 volumio sudo[1786731]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:16 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "wizard"... Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "networkfs"... Feb 08 03:06:16 volumio volumio[1786700]: info: Starting Udev Watcher for removable devices Feb 08 03:06:16 volumio volumio[1786700]: info: Ignoring mount for partition: boot Feb 08 03:06:16 volumio volumio[1786700]: info: Ignoring mount for partition: volumio Feb 08 03:06:16 volumio volumio[1786700]: info: Ignoring mount for partition: volumio_data Feb 08 03:06:16 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "volumio_command_line_client"... Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "upnp"... Feb 08 03:06:16 volumio volumio[1786700]: info: [1770494776315] Starting Upmpd Daemon Feb 08 03:06:16 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "my_music"... Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "mpd"... Feb 08 03:06:16 volumio volumio[1786700]: info: Loading plugin "upnp_browser"... Feb 08 03:06:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 08 03:06:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:16 volumio go-librespot[1786767]: go-librespot daemon starting... Feb 08 03:06:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:16 volumio go-librespot[1786768]: time="2026-02-08T03:06:16+07:00" level=info msg="running go-librespot 0.6.2" Feb 08 03:06:16 volumio go-librespot[1786768]: time="2026-02-08T03:06:16+07:00" level=debug msg="app state loaded" Feb 08 03:06:16 volumio go-librespot[1786768]: time="2026-02-08T03:06:16+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 03:06:17 volumio volumio[1786700]: info: Starting UPNP Browser Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "alarm-clock"... Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "airplay_emulation"... Feb 08 03:06:17 volumio volumio[1786700]: info: Starting Shairport Sync Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "last_100"... Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "webradio"... Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "i2s_dacs"... Feb 08 03:06:17 volumio volumio[1786700]: info: I2S DAC not set, start Auto-detection Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "volumiodiscovery"... Feb 08 03:06:17 volumio volumio[1786700]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:17 volumio volumio[1786700]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:17 volumio volumio[1786700]: *** WARNING *** For more information see Feb 08 03:06:17 volumio volumio[1786700]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:17 volumio volumio[1786700]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:17 volumio volumio[1786700]: *** WARNING *** For more information see Feb 08 03:06:17 volumio node[1786700]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:17 volumio node[1786700]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:17 volumio node[1786700]: *** WARNING *** For more information see Feb 08 03:06:17 volumio node[1786700]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:17 volumio node[1786700]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:17 volumio node[1786700]: *** WARNING *** For more information see Feb 08 03:06:17 volumio volumio[1786700]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 08 03:06:17 volumio volumio[1786700]: info: Discovery: Started advertising with name: Volumio Feb 08 03:06:17 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "jellyfin"... Feb 08 03:06:17 volumio go-librespot[1786768]: time="2026-02-08T03:06:17+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 08 03:06:17 volumio go-librespot[1786768]: time="2026-02-08T03:06:17+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 08 03:06:17 volumio go-librespot[1786768]: time="2026-02-08T03:06:17+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 08 03:06:17 volumio go-librespot[1786768]: time="2026-02-08T03:06:17+07:00" level=info msg="zeroconf server listening on port 44445" Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "spop"... Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "youtube2"... Feb 08 03:06:17 volumio go-librespot[1786768]: time="2026-02-08T03:06:17+07:00" level=debug msg="obtained new client token: AADHwHkDEpWEG8ZD8WTE1TvGDWH066iF5DZv0KGyT6Hb6wIqHieQRuoA37jKDp7RytabDnyqRtbb9cQ7BCfuZXam+7sS5D4LMj96Y2G1ame1WyHoMVnWUv4oKH9heQHznOGNraFNZZAF2r+nZ+V3sNfPPxG1xJjsvrQG6Bf46XjOhZ3xVVtcqU3KhJ4ftea/IC98LTDs65FpYd9UmG6XaVhzepT6JzZeKdwK/xkGv/Iy/3j7TYC85aRgBw==" Feb 08 03:06:17 volumio go-librespot[1786768]: time="2026-02-08T03:06:17+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 08 03:06:17 volumio volumio[1786700]: info: Loading plugin "ytmusic"... Feb 08 03:06:18 volumio volumio[1786700]: info: Loading plugin "outputs"... Feb 08 03:06:18 volumio volumio[1786700]: info: Loading plugin "albumart"... Feb 08 03:06:18 volumio volumio[1786700]: info: Plugin example_plugin is not enabled Feb 08 03:06:18 volumio volumio[1786700]: info: Loading plugin "inputs"... Feb 08 03:06:18 volumio volumio[1786700]: info: Loading plugin "updater_comm"... Feb 08 03:06:18 volumio volumio[1786700]: info: Plugin mpdemulation is not enabled Feb 08 03:06:18 volumio volumio[1786700]: info: Loading plugin "rest_api"... Feb 08 03:06:18 volumio volumio[1786700]: info: Loading plugin "websocket"... Feb 08 03:06:18 volumio volumio[1786700]: info: Starting Socket.io Server version 1.7.4 Feb 08 03:06:18 volumio volumio[1786700]: info: Loading i18n strings for locale ru Feb 08 03:06:18 volumio volumio[1786779]: Forking 1 albumart workers Feb 08 03:06:18 volumio volumio[1786700]: Updating browse sources language Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:18 volumio go-librespot[1786768]: time="2026-02-08T03:06:18+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:18 volumio go-librespot[1786768]: time="2026-02-08T03:06:18+07:00" level=debug msg="completed challenge" Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::initPlayerControls Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: Express server listening on port 3000 Feb 08 03:06:18 volumio volumio[1786700]: [Metrics] WebUI: 3s 74.72ms Feb 08 03:06:18 volumio volumio[1786700]: info: Setting Device type: x86 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreStateMachine::resetVolumioState Feb 08 03:06:18 volumio volumio[1786700]: info: CoreStateMachine::getcurrentVolume Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 08 03:06:18 volumio volumio[1786700]: info: Completed loading Core Plugins Feb 08 03:06:18 volumio volumio[1786700]: info: Preparing to generate the ALSA configuration file Feb 08 03:06:18 volumio go-librespot[1786768]: time="2026-02-08T03:06:18+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:18 volumio volumio[1786700]: info: Volumio Network Manager: Network status updated: 1 Feb 08 03:06:18 volumio volumio[1786700]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 08 03:06:18 volumio volumio[1786700]: wlan0 Interface doesn't support scanning. Feb 08 03:06:18 volumio volumio[1786700]: info: Cannot use regular scanning, forcing with ap-force Feb 08 03:06:18 volumio sudo[1786804]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 08 03:06:18 volumio sudo[1786804]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:18 volumio sudo[1786804]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:18 volumio volumio[1786700]: command failed: No such device (-19) Feb 08 03:06:18 volumio volumio[1786700]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 08 03:06:18 volumio volumio[1786700]: command failed: No such device (-19) Feb 08 03:06:18 volumio volumio[1786700]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 08 03:06:18 volumio volumio[1786700]: info: VolumeController:: Volume=48 Mute =false Feb 08 03:06:18 volumio volumio[1786700]: info: CoreStateMachine::pushState Feb 08 03:06:18 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:18 volumio volumio[1786700]: info: CoreStateMachine::updateTrackBlock Feb 08 03:06:18 volumio volumio[1786700]: info: CorePlayQueue::getTrackBlock Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:18 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:18 volumio volumio[1786700]: info: Reloading queue from file Feb 08 03:06:18 volumio volumio[1786789]: Starting albumart workers Feb 08 03:06:18 volumio volumio[1786700]: info: CoreStateMachine::setRepeat null single undefined Feb 08 03:06:18 volumio volumio[1786700]: info: CoreStateMachine::pushState Feb 08 03:06:18 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:18 volumio volumio[1786700]: info: CoreStateMachine::setRandom null Feb 08 03:06:18 volumio volumio[1786700]: info: CoreStateMachine::pushState Feb 08 03:06:18 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:18 volumio volumio[1786700]: info: Asound.conf file unchanged, so no further update is needed Feb 08 03:06:18 volumio volumio[1786700]: info: Output device has changed, restarting MPD Feb 08 03:06:18 volumio sudo[1786815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 03:06:18 volumio sudo[1786815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:18 volumio volumio[1786700]: info: Output device has changed, restarting Shairport Sync Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:18 volumio sudo[1786815]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:18 volumio volumio[1786700]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 03:06:18 volumio volumio[1786700]: info: ___________ START PLUGINS ___________ Feb 08 03:06:18 volumio sudo[1786818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 03:06:18 volumio volumio[1786700]: info: ControllerMpd::onStart: Initializing MPD Feb 08 03:06:18 volumio volumio[1786700]: info: Creating MPD Configuration file Feb 08 03:06:18 volumio sudo[1786818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:18 volumio volumio[1786700]: info: [1770494778516] CoreMusicLibrary::Adding element Медиа-серверы Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:18 volumio volumio[1786700]: info: UPNP Browser: Client initialized successfully Feb 08 03:06:18 volumio sudo[1786825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 03:06:18 volumio sudo[1786825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 03:06:18 volumio sudo[1786825]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:18 volumio sudo[1786827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 03:06:18 volumio sudo[1786827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:18 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 03:06:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 03:06:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 03:06:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 03:06:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 03:06:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 03:06:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 03:06:18 volumio volumio[1786700]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 03:06:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 03:06:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 03:06:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 03:06:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 03:06:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 03:06:18 volumio volumio[1786700]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:18 volumio volumio[1786700]: info: [1770494778591] CoreMusicLibrary::Adding element Last_100 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:18 volumio volumio[1786700]: info: [1770494778592] CoreMusicLibrary::Adding element Webradio Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 03:06:18 volumio volumio[1786700]: info: Initializing BBC Radios Feb 08 03:06:18 volumio sudo[1786839]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 03:06:18 volumio sudo[1786839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 03:06:18 volumio sudo[1786839]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 08 03:06:18 volumio volumio[1786700]: info: Discovery: Getting this device information Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:18 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:18 volumio volumio[1786700]: info: [1770494778706] CoreMusicLibrary::Adding element Jellyfin Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:18 volumio volumio[1786700]: Cannot find translation for source Jellyfin Feb 08 03:06:18 volumio volumio[1786700]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.1.133","id":"46e269a1-2f26-4df8-b5f6-1749968c4324","name":"Volumio","serviceName":"Volumio","state":{"albumart":"https://i.scdn.co/image/ab67616d0000b2732f2c4ed053b2399461163048","artist":"Ozma","mute":false,"status":"stop","track":"Эхо","volume":48},"type":"device"} Feb 08 03:06:18 volumio volumio[1786700]: info: Creating Spotify config file Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:18 volumio go-librespot[1786768]: time="2026-02-08T03:06:18+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:18 volumio volumio[1786700]: info: [1770494778904] CoreMusicLibrary::Adding element YouTube2 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:18 volumio volumio[1786700]: Cannot find translation for source Jellyfin Feb 08 03:06:18 volumio volumio[1786700]: Cannot find translation for source YouTube2 Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:18 volumio volumio[1786700]: info: [1770494778911] CoreMusicLibrary::Adding element YouTube Music Feb 08 03:06:18 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:18 volumio volumio[1786700]: Cannot find translation for source Jellyfin Feb 08 03:06:18 volumio volumio[1786700]: Cannot find translation for source YouTube2 Feb 08 03:06:18 volumio volumio[1786700]: Cannot find translation for source YouTube Music Feb 08 03:06:18 volumio volumio[1786700]: info: Volumio Calling Home Feb 08 03:06:19 volumio mpd[1786843]: 2026-02-08T03:06:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 03:06:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 03:06:19 volumio sudo[1786827]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:19 volumio sudo[1786818]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:19 volumio volumio[1786700]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 08 03:06:19 volumio go-librespot[1786768]: time="2026-02-08T03:06:19+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:19 volumio go-librespot[1786768]: time="2026-02-08T03:06:19+07:00" level=debug msg="completed challenge" Feb 08 03:06:19 volumio volumio[1786700]: info: Completed starting Core Plugins Feb 08 03:06:19 volumio volumio[1786700]: info: ------------------------------------------- Feb 08 03:06:19 volumio volumio[1786700]: info: ----- MyVolumio plugins startup ---- Feb 08 03:06:19 volumio volumio[1786700]: info: ------------------------------------------- Feb 08 03:06:19 volumio volumio[1786700]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 03:06:19 volumio volumio[1786700]: info: MPD Permissions set Feb 08 03:06:19 volumio volumio[1786700]: info: MPD Permissions set Feb 08 03:06:19 volumio volumio[1786700]: info: VolumeController:: Volume=48 Mute =false Feb 08 03:06:19 volumio volumio[1786700]: info: CoreStateMachine::pushState Feb 08 03:06:19 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:19 volumio volumio[1786700]: info: Spotify config file written Feb 08 03:06:19 volumio sudo[1786873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 03:06:19 volumio sudo[1786873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:19 volumio volumio[1786700]: error: MPD error: The expression evaluated to a falsy value: Feb 08 03:06:19 volumio volumio[1786700]: assert.ok(self.idling) Feb 08 03:06:19 volumio volumio[1786700]: error: The expression evaluated to a falsy value: Feb 08 03:06:19 volumio volumio[1786700]: assert.ok(self.idling) Feb 08 03:06:19 volumio volumio[1786700]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 08 03:06:19 volumio volumio[1786700]: info: MPD running with PID1786843 Feb 08 03:06:19 volumio volumio[1786700]: ,establishing connection Feb 08 03:06:19 volumio volumio[1786700]: info: Volumio called home Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 08 03:06:19 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 08 03:06:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 08 03:06:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:19 volumio go-librespot[1786877]: go-librespot daemon starting... Feb 08 03:06:19 volumio sudo[1786873]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:19 volumio go-librespot[1786882]: time="2026-02-08T03:06:19+07:00" level=info msg="running go-librespot 0.6.2" Feb 08 03:06:19 volumio go-librespot[1786882]: time="2026-02-08T03:06:19+07:00" level=debug msg="app state loaded" Feb 08 03:06:19 volumio go-librespot[1786882]: time="2026-02-08T03:06:19+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:19 volumio volumio[1786700]: info: No need to fix Spotify hosts Feb 08 03:06:19 volumio volumio[1786700]: info: Discovery: adding 46e269a1-2f26-4df8-b5f6-1749968c4324 Feb 08 03:06:19 volumio volumio[1786700]: info: Discovery: Found device Volumio Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:19 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:19 volumio volumio[1786700]: error: updateQueue error: null Feb 08 03:06:19 volumio volumio[1786700]: info: [jellyfin-poller] Polled http://192.168.1.202:8096: online Feb 08 03:06:19 volumio volumio[1786700]: info: Discovery: this is already registered, 46e269a1-2f26-4df8-b5f6-1749968c4324 Feb 08 03:06:19 volumio volumio[1786700]: info: Discovery: Found device Volumio Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:19 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:19 volumio volumio[1786700]: error: updateQueue error: null Feb 08 03:06:19 volumio volumio[1786700]: info: Starting Shairport Sync Feb 08 03:06:19 volumio volumio[1786700]: info: Starting Shairport Sync Feb 08 03:06:19 volumio volumio[1786700]: info: Starting Shairport Sync Feb 08 03:06:19 volumio sudo[1786901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 03:06:19 volumio sudo[1786903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 03:06:19 volumio sudo[1786901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:19 volumio sudo[1786903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:19 volumio sudo[1786905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 03:06:19 volumio sudo[1786905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:19 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 03:06:19 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 03:06:19 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:19 volumio systemd[1]: shairport-sync.service: Consumed 1.037s CPU time. Feb 08 03:06:19 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:19 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:19 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 03:06:19 volumio sudo[1786901]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:19 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 03:06:19 volumio volumio[1786700]: info: Shairport-Sync Started Feb 08 03:06:19 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:19 volumio volumio[1786700]: Error adding Membership: Error: addMembership EINVAL Feb 08 03:06:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:19 volumio sudo[1786903]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:19 volumio sudo[1786905]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:19 volumio volumio[1786700]: info: Shairport-Sync Started Feb 08 03:06:19 volumio volumio[1786700]: info: Shairport-Sync Started Feb 08 03:06:19 volumio volumio[1786700]: info: An error occurred while refreshing Spotify Token Error: Bad Request Feb 08 03:06:19 volumio go-librespot[1786882]: time="2026-02-08T03:06:19+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 08 03:06:19 volumio go-librespot[1786882]: time="2026-02-08T03:06:19+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 08 03:06:19 volumio go-librespot[1786882]: time="2026-02-08T03:06:19+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 08 03:06:19 volumio go-librespot[1786882]: time="2026-02-08T03:06:19+07:00" level=info msg="zeroconf server listening on port 43115" Feb 08 03:06:19 volumio volumio-remote-updater[721]: [2026-02-08 03:06:19] [connect] Successful connection Feb 08 03:06:19 volumio volumio-remote-updater[721]: [2026-02-08 03:06:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770494779 101 Feb 08 03:06:19 volumio volumio[1786700]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Feb 08 03:06:20 volumio go-librespot[1786882]: time="2026-02-08T03:06:20+07:00" level=debug msg="obtained new client token: AABeYEInmTQYrX448Tur9h0qYTSXBKnsnXWRoxDO0rW3N5RCJiXospdCjqFLSwCnfhpnBn6lYdQcoRtrWQCbU86heqZ2bHKfW7mF4DFbq7flEQ2pL7FLb0OF7ZXG/WkIzU+lbBXsJ53k2MAx/wkuLC/U/ZpyCEtpZCy7GetV0HsVixrNB4InOdrqGD/Le1LowJ5G4fVMQhBnE/IDCeeF6eeIdYPoeixz7NcolnyKYuo3nWCsNLLJcL8=" Feb 08 03:06:20 volumio go-librespot[1786882]: time="2026-02-08T03:06:20+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 08 03:06:20 volumio go-librespot[1786882]: time="2026-02-08T03:06:20+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:20 volumio go-librespot[1786882]: time="2026-02-08T03:06:20+07:00" level=debug msg="completed challenge" Feb 08 03:06:20 volumio go-librespot[1786882]: time="2026-02-08T03:06:20+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:20 volumio volumio[1786700]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetVisibleSources Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:20 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:20 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 08 03:06:20 volumio volumio[1786700]: info: Received Get System Info Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 08 03:06:20 volumio volumio[1786700]: info: Discovery: Getting this device information Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:20 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 03:06:20 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:20 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:20 volumio volumio[1786700]: info: Listing playlists Feb 08 03:06:21 volumio go-librespot[1786882]: time="2026-02-08T03:06:21+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 08 03:06:21 volumio go-librespot[1786882]: time="2026-02-08T03:06:21+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:21 volumio go-librespot[1786882]: time="2026-02-08T03:06:21+07:00" level=debug msg="completed challenge" Feb 08 03:06:21 volumio go-librespot[1786882]: time="2026-02-08T03:06:21+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:21 volumio go-librespot[1786882]: time="2026-02-08T03:06:21+07:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 08 03:06:22 volumio volumio[1786700]: info: go-librespot daemon successfully initialized Feb 08 03:06:22 volumio go-librespot[1786882]: time="2026-02-08T03:06:22+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:22 volumio go-librespot[1786882]: time="2026-02-08T03:06:22+07:00" level=debug msg="completed challenge" Feb 08 03:06:22 volumio go-librespot[1786882]: time="2026-02-08T03:06:22+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:22 volumio go-librespot[1786882]: time="2026-02-08T03:06:22+07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 03:06:23 volumio go-librespot[1786882]: time="2026-02-08T03:06:23+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:23 volumio go-librespot[1786882]: time="2026-02-08T03:06:23+07:00" level=debug msg="completed challenge" Feb 08 03:06:23 volumio go-librespot[1786882]: time="2026-02-08T03:06:23+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:24 volumio go-librespot[1786882]: time="2026-02-08T03:06:24+07:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 08 03:06:24 volumio volumio[1786700]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 08 03:06:24 volumio go-librespot[1786882]: time="2026-02-08T03:06:24+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:24 volumio go-librespot[1786882]: time="2026-02-08T03:06:24+07:00" level=debug msg="completed challenge" Feb 08 03:06:24 volumio go-librespot[1786882]: time="2026-02-08T03:06:24+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:25 volumio volumio[1786700]: info: Initializing connection to go-librespot Websocket Feb 08 03:06:25 volumio go-librespot[1786882]: time="2026-02-08T03:06:25+07:00" level=debug msg="new websocket client" Feb 08 03:06:25 volumio volumio[1786700]: info: Connection to go-librespot Websocket established Feb 08 03:06:25 volumio go-librespot[1786882]: time="2026-02-08T03:06:25+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 08 03:06:26 volumio go-librespot[1786882]: time="2026-02-08T03:06:26+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:26 volumio go-librespot[1786882]: time="2026-02-08T03:06:26+07:00" level=debug msg="completed challenge" Feb 08 03:06:26 volumio volumio[1786700]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:26 volumio volumio[1786700]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:26 volumio volumio[1786700]: info: Listing playlists Feb 08 03:06:26 volumio volumio[1786700]: info: Listing playlists Feb 08 03:06:26 volumio sudo[1786952]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 03:06:26 volumio sudo[1786950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 03:06:26 volumio sudo[1786952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:26 volumio sudo[1786950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:26 volumio sudo[1786952]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:26 volumio sudo[1786950]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:26 volumio go-librespot[1786882]: time="2026-02-08T03:06:26+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:26 volumio go-librespot[1786882]: time="2026-02-08T03:06:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:26 volumio sudo[1786958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 08 03:06:26 volumio sudo[1786958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 03:06:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 03:06:26 volumio volumio[1786700]: info: Connection to go-librespot Websocket closed Feb 08 03:06:26 volumio sudo[1786958]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:26 volumio volumio[1786700]: info: Upmpdcli Daemon Started Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 08 03:06:27 volumio volumio[1786700]: info: Adding plugin bluetooth to MyMusic Plugins Feb 08 03:06:27 volumio volumio[1786700]: info: Adding plugin multiroom to MyMusic Plugins Feb 08 03:06:27 volumio volumio[1786700]: info: Adding plugin metavolumio to MyMusic Plugins Feb 08 03:06:27 volumio volumio[1786700]: info: Adding plugin cd_controller to MyMusic Plugins Feb 08 03:06:27 volumio volumio[1786700]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 08 03:06:27 volumio volumio[1786700]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 08 03:06:27 volumio volumio[1786700]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 08 03:06:27 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:27 volumio volumio[1786700]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:27 volumio volumio[1786700]: info: Starting MyVolumio Remote Streaming Endpoints Feb 08 03:06:27 volumio volumio[1786700]: info: MyVolumio login type: Token Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 08 03:06:27 volumio volumio[1786700]: info: Streaming services startup Feb 08 03:06:27 volumio volumio[1786700]: info: Starting Streaming Daemon Feb 08 03:06:27 volumio volumio[1786700]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 08 03:06:27 volumio sudo[1786962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 08 03:06:27 volumio sudo[1786962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:27 volumio sudo[1786962]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:28 volumio volumio[1786700]: error: Cannot start Volumio Streaming Daemon Feb 08 03:06:28 volumio volumio[1786700]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 08 03:06:28 volumio volumio[1786700]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 08 03:06:28 volumio volumio[1786700]: info: Getting Spotify volume Feb 08 03:06:28 volumio volumio[1786700]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 03:06:28 volumio volumio[1786700]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 03:06:28 volumio volumio[1786700]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 08 03:06:28 volumio volumio[1786700]: errno: -111, Feb 08 03:06:28 volumio volumio[1786700]: code: 'ECONNREFUSED', Feb 08 03:06:28 volumio volumio[1786700]: syscall: 'connect', Feb 08 03:06:28 volumio volumio[1786700]: address: '127.0.0.1', Feb 08 03:06:28 volumio volumio[1786700]: port: 9879, Feb 08 03:06:28 volumio volumio[1786700]: response: undefined Feb 08 03:06:28 volumio volumio[1786700]: } Feb 08 03:06:28 volumio volumio[1786700]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 03:06:28 volumio sudo[1786983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 03:05' Feb 08 03:06:28 volumio sudo[1786983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:28 volumio sudo[1786983]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:29 volumio volumio-remote-updater[721]: [2026-02-08 03:06:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 08 03:06:29 volumio volumio-remote-updater[721]: [2026-02-08 03:06:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 08 03:06:29 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 08 03:06:29 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 08 03:06:29 volumio systemd[1]: volumio.service: Consumed 3.915s CPU time. Feb 08 03:06:29 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 03:06:29 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 03:06:29 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5043. Feb 08 03:06:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 08 03:06:29 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 03:06:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:29 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 08 03:06:29 volumio systemd[1]: volumio.service: Consumed 3.915s CPU time. Feb 08 03:06:29 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 08 03:06:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:29 volumio go-librespot[1787001]: go-librespot daemon starting... Feb 08 03:06:29 volumio go-librespot[1787003]: time="2026-02-08T03:06:29+07:00" level=info msg="running go-librespot 0.6.2" Feb 08 03:06:29 volumio go-librespot[1787003]: time="2026-02-08T03:06:29+07:00" level=debug msg="app state loaded" Feb 08 03:06:29 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 03:06:29 volumio go-librespot[1787003]: time="2026-02-08T03:06:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 03:06:30 volumio go-librespot[1787003]: time="2026-02-08T03:06:30+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 08 03:06:30 volumio go-librespot[1787003]: time="2026-02-08T03:06:30+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 08 03:06:30 volumio go-librespot[1787003]: time="2026-02-08T03:06:30+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 08 03:06:30 volumio go-librespot[1787003]: time="2026-02-08T03:06:30+07:00" level=info msg="zeroconf server listening on port 36365" Feb 08 03:06:30 volumio volumio[1787000]: info: ------------------------------------------- Feb 08 03:06:30 volumio volumio[1787000]: info: ----- Volumio3 ---- Feb 08 03:06:30 volumio volumio[1787000]: info: ------------------------------------------- Feb 08 03:06:30 volumio volumio[1787000]: info: ----- System startup ---- Feb 08 03:06:30 volumio volumio[1787000]: info: ------------------------------------------- Feb 08 03:06:30 volumio go-librespot[1787003]: time="2026-02-08T03:06:30+07:00" level=debug msg="obtained new client token: AABKR+96w/ARlpXoRRUaAZpn+/QvyKAbA8vz0Xlj88eQskjtQyK3KpQLtVgv0p38mGIuFzgAv7Lg++QraDfnerIfPNUL1XfkuLqTd6/Zz4jXZ9jkdOxdLzNxQtbkWZ10FT9NHduuyjFcmcz0KUKPuUbK+U9gE7yPq9efd8JhCBFdxcpCf9tMfmqWethwpJ1GQXOau+U4bdWENmkhdVsd/bG2U19IW4Lm+UUtQe+k1w/mfoZqhKbQbtVisw==" Feb 08 03:06:30 volumio go-librespot[1787003]: time="2026-02-08T03:06:30+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 08 03:06:30 volumio volumio[1787000]: info: MYVOLUMIO Environment detected Feb 08 03:06:30 volumio volumio[1787000]: info: Plugin folders cleanup Feb 08 03:06:30 volumio volumio[1787000]: info: Scanning into folder /volumio/app/plugins/ Feb 08 03:06:30 volumio volumio[1787000]: info: Scanning category audio_interface Feb 08 03:06:30 volumio volumio[1787000]: info: Scanning category miscellanea Feb 08 03:06:30 volumio volumio[1787000]: info: Scanning category music_service Feb 08 03:06:30 volumio volumio[1787000]: info: Scanning category plugins.json Feb 08 03:06:30 volumio volumio[1787000]: info: Scanning category system_controller Feb 08 03:06:30 volumio volumio[1787000]: info: Scanning category user_interface Feb 08 03:06:30 volumio volumio[1787000]: info: Scanning into folder /data/plugins/ Feb 08 03:06:30 volumio volumio[1787000]: info: Scanning category music_service Feb 08 03:06:30 volumio volumio[1787000]: info: Plugin folders cleanup completed Feb 08 03:06:30 volumio volumio[1787000]: info: ------------------------------------------- Feb 08 03:06:30 volumio volumio[1787000]: info: ----- Core plugins startup ---- Feb 08 03:06:30 volumio volumio[1787000]: info: ------------------------------------------- Feb 08 03:06:30 volumio volumio[1787000]: info: Loading plugins from folder /volumio/app/plugins/ Feb 08 03:06:30 volumio volumio[1787000]: info: Adding plugin upnp to MyMusic Plugins Feb 08 03:06:30 volumio volumio[1787000]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 08 03:06:30 volumio volumio[1787000]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 08 03:06:30 volumio volumio[1787000]: info: Loading plugins from folder /data/plugins/ Feb 08 03:06:30 volumio volumio[1787000]: info: Loading plugin "system"... Feb 08 03:06:30 volumio volumio[1787000]: info: Loading plugin "appearance"... Feb 08 03:06:30 volumio go-librespot[1787003]: time="2026-02-08T03:06:30+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:30 volumio go-librespot[1787003]: time="2026-02-08T03:06:30+07:00" level=debug msg="completed challenge" Feb 08 03:06:30 volumio go-librespot[1787003]: time="2026-02-08T03:06:30+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "network"... Feb 08 03:06:31 volumio volumio[1787000]: info: Refreshing Cached IP Addresses Feb 08 03:06:31 volumio sudo[1787039]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 03:06:31 volumio sudo[1787039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:31 volumio sudo[1787039]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:31 volumio sudo[1787041]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 03:06:31 volumio sudo[1787041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "services"... Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "alsa_controller"... Feb 08 03:06:31 volumio sudo[1787041]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:31 volumio sudo[1787049]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 08 03:06:31 volumio sudo[1787049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:31 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "wizard"... Feb 08 03:06:31 volumio sudo[1787049]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "networkfs"... Feb 08 03:06:31 volumio volumio[1787000]: info: Starting Udev Watcher for removable devices Feb 08 03:06:31 volumio volumio[1787000]: info: Ignoring mount for partition: boot Feb 08 03:06:31 volumio volumio[1787000]: info: Ignoring mount for partition: volumio Feb 08 03:06:31 volumio volumio[1787000]: info: Ignoring mount for partition: volumio_data Feb 08 03:06:31 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "volumio_command_line_client"... Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "upnp"... Feb 08 03:06:31 volumio volumio[1787000]: info: [1770494791176] Starting Upmpd Daemon Feb 08 03:06:31 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "my_music"... Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "mpd"... Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "upnp_browser"... Feb 08 03:06:31 volumio go-librespot[1787003]: time="2026-02-08T03:06:31+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 08 03:06:31 volumio volumio[1787000]: info: Starting UPNP Browser Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "alarm-clock"... Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "airplay_emulation"... Feb 08 03:06:31 volumio volumio[1787000]: info: Starting Shairport Sync Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "last_100"... Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "webradio"... Feb 08 03:06:31 volumio go-librespot[1787003]: time="2026-02-08T03:06:31+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:31 volumio go-librespot[1787003]: time="2026-02-08T03:06:31+07:00" level=debug msg="completed challenge" Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "i2s_dacs"... Feb 08 03:06:31 volumio volumio[1787000]: info: I2S DAC not set, start Auto-detection Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "volumiodiscovery"... Feb 08 03:06:31 volumio volumio[1787000]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:31 volumio volumio[1787000]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:31 volumio volumio[1787000]: *** WARNING *** For more information see Feb 08 03:06:31 volumio volumio[1787000]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:31 volumio volumio[1787000]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:31 volumio node[1787000]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:31 volumio volumio[1787000]: *** WARNING *** For more information see Feb 08 03:06:31 volumio node[1787000]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:31 volumio node[1787000]: *** WARNING *** For more information see Feb 08 03:06:31 volumio node[1787000]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:31 volumio node[1787000]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:31 volumio node[1787000]: *** WARNING *** For more information see Feb 08 03:06:31 volumio volumio[1787000]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 08 03:06:31 volumio volumio[1787000]: info: Discovery: Started advertising with name: Volumio Feb 08 03:06:31 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:31 volumio volumio[1787000]: info: Loading plugin "jellyfin"... Feb 08 03:06:31 volumio go-librespot[1787003]: time="2026-02-08T03:06:31+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:32 volumio volumio[1787000]: info: Loading plugin "spop"... Feb 08 03:06:32 volumio volumio[1787000]: info: Loading plugin "youtube2"... Feb 08 03:06:32 volumio go-librespot[1787003]: time="2026-02-08T03:06:32+07:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 08 03:06:32 volumio volumio[1787000]: info: Loading plugin "ytmusic"... Feb 08 03:06:32 volumio volumio[1787000]: info: Loading plugin "outputs"... Feb 08 03:06:32 volumio volumio[1787000]: info: Loading plugin "albumart"... Feb 08 03:06:32 volumio volumio[1787000]: info: Plugin example_plugin is not enabled Feb 08 03:06:32 volumio volumio[1787000]: info: Loading plugin "inputs"... Feb 08 03:06:32 volumio volumio[1787000]: info: Loading plugin "updater_comm"... Feb 08 03:06:32 volumio go-librespot[1787003]: time="2026-02-08T03:06:32+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:32 volumio go-librespot[1787003]: time="2026-02-08T03:06:32+07:00" level=debug msg="completed challenge" Feb 08 03:06:32 volumio volumio[1787000]: info: Plugin mpdemulation is not enabled Feb 08 03:06:32 volumio volumio[1787000]: info: Loading plugin "rest_api"... Feb 08 03:06:32 volumio volumio[1787000]: info: Loading plugin "websocket"... Feb 08 03:06:32 volumio volumio[1787000]: info: Starting Socket.io Server version 1.7.4 Feb 08 03:06:32 volumio volumio[1787000]: info: Loading i18n strings for locale ru Feb 08 03:06:32 volumio volumio[1787080]: Forking 1 albumart workers Feb 08 03:06:32 volumio volumio[1787000]: Updating browse sources language Feb 08 03:06:32 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:32 volumio go-librespot[1787003]: time="2026-02-08T03:06:32+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::initPlayerControls Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: Express server listening on port 3000 Feb 08 03:06:33 volumio volumio[1787000]: [Metrics] WebUI: 3s 91.21ms Feb 08 03:06:33 volumio volumio[1787000]: info: Setting Device type: x86 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreStateMachine::resetVolumioState Feb 08 03:06:33 volumio volumio[1787000]: info: CoreStateMachine::getcurrentVolume Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 08 03:06:33 volumio volumio[1787000]: info: Completed loading Core Plugins Feb 08 03:06:33 volumio volumio[1787000]: info: Preparing to generate the ALSA configuration file Feb 08 03:06:33 volumio volumio[1787000]: info: Volumio Network Manager: Network status updated: 1 Feb 08 03:06:33 volumio volumio[1787000]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 08 03:06:33 volumio volumio[1787000]: wlan0 Interface doesn't support scanning. Feb 08 03:06:33 volumio volumio[1787000]: info: Cannot use regular scanning, forcing with ap-force Feb 08 03:06:33 volumio sudo[1787104]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 08 03:06:33 volumio sudo[1787104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:33 volumio sudo[1787104]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:33 volumio volumio[1787000]: command failed: No such device (-19) Feb 08 03:06:33 volumio volumio[1787000]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 08 03:06:33 volumio volumio[1787000]: command failed: No such device (-19) Feb 08 03:06:33 volumio volumio[1787000]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 08 03:06:33 volumio volumio[1787000]: info: VolumeController:: Volume=48 Mute =false Feb 08 03:06:33 volumio volumio[1787000]: info: CoreStateMachine::pushState Feb 08 03:06:33 volumio volumio[1787000]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:33 volumio volumio[1787000]: info: CoreStateMachine::updateTrackBlock Feb 08 03:06:33 volumio volumio[1787000]: info: CorePlayQueue::getTrackBlock Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 03:06:33 volumio volumio[1787090]: Starting albumart workers Feb 08 03:06:33 volumio volumio[1787000]: info: Reloading queue from file Feb 08 03:06:33 volumio volumio[1787000]: info: CoreStateMachine::setRepeat null single undefined Feb 08 03:06:33 volumio volumio[1787000]: info: CoreStateMachine::pushState Feb 08 03:06:33 volumio volumio[1787000]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:33 volumio volumio[1787000]: info: CoreStateMachine::setRandom null Feb 08 03:06:33 volumio volumio[1787000]: info: CoreStateMachine::pushState Feb 08 03:06:33 volumio volumio[1787000]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:33 volumio volumio[1787000]: info: VolumeController:: Volume=48 Mute =false Feb 08 03:06:33 volumio volumio[1787000]: info: CoreStateMachine::pushState Feb 08 03:06:33 volumio volumio[1787000]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:33 volumio volumio[1787000]: info: Asound.conf file unchanged, so no further update is needed Feb 08 03:06:33 volumio volumio[1787000]: info: Output device has changed, restarting MPD Feb 08 03:06:33 volumio volumio[1787000]: info: Output device has changed, restarting Shairport Sync Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:33 volumio sudo[1787118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 03:06:33 volumio sudo[1787118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:33 volumio sudo[1787118]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:33 volumio volumio[1787000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 03:06:33 volumio sudo[1787120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 03:06:33 volumio sudo[1787120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:33 volumio volumio[1787000]: info: ___________ START PLUGINS ___________ Feb 08 03:06:33 volumio volumio[1787000]: info: ControllerMpd::onStart: Initializing MPD Feb 08 03:06:33 volumio volumio[1787000]: info: Creating MPD Configuration file Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:33 volumio volumio[1787000]: info: [1770494793478] CoreMusicLibrary::Adding element Медиа-серверы Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:33 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 03:06:33 volumio sudo[1787128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 03:06:33 volumio sudo[1787128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:33 volumio go-librespot[1787003]: time="2026-02-08T03:06:33+07:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 08 03:06:33 volumio volumio[1787000]: info: UPNP Browser: Client initialized successfully Feb 08 03:06:33 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 03:06:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 03:06:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 03:06:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 03:06:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 03:06:33 volumio sudo[1787128]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 03:06:33 volumio sudo[1787130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 03:06:33 volumio sudo[1787130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:33 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 03:06:33 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 03:06:33 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 03:06:33 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 03:06:33 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 03:06:33 volumio volumio[1787000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:33 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 03:06:33 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 03:06:33 volumio volumio[1787000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:33 volumio volumio[1787000]: info: [1770494793603] CoreMusicLibrary::Adding element Last_100 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:33 volumio volumio[1787000]: info: [1770494793604] CoreMusicLibrary::Adding element Webradio Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 03:06:33 volumio volumio[1787000]: info: Initializing BBC Radios Feb 08 03:06:33 volumio sudo[1787141]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 03:06:33 volumio sudo[1787141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 03:06:33 volumio sudo[1787141]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 08 03:06:33 volumio volumio[1787000]: info: Discovery: Getting this device information Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:33 volumio volumio[1787000]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:33 volumio volumio[1787000]: info: [1770494793674] CoreMusicLibrary::Adding element Jellyfin Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:33 volumio volumio[1787000]: Cannot find translation for source Jellyfin Feb 08 03:06:33 volumio volumio[1787000]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.1.133","id":"46e269a1-2f26-4df8-b5f6-1749968c4324","name":"Volumio","serviceName":"Volumio","state":{"albumart":"https://i.scdn.co/image/ab67616d0000b2732f2c4ed053b2399461163048","artist":"Ozma","mute":false,"status":"stop","track":"Эхо","volume":48},"type":"device"} Feb 08 03:06:33 volumio volumio[1787000]: info: Creating Spotify config file Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:33 volumio volumio[1787000]: info: [1770494793908] CoreMusicLibrary::Adding element YouTube2 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:33 volumio volumio[1787000]: Cannot find translation for source Jellyfin Feb 08 03:06:33 volumio volumio[1787000]: Cannot find translation for source YouTube2 Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:33 volumio volumio[1787000]: info: [1770494793910] CoreMusicLibrary::Adding element YouTube Music Feb 08 03:06:33 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:33 volumio volumio[1787000]: Cannot find translation for source Jellyfin Feb 08 03:06:33 volumio volumio[1787000]: Cannot find translation for source YouTube2 Feb 08 03:06:33 volumio volumio[1787000]: Cannot find translation for source YouTube Music Feb 08 03:06:33 volumio volumio[1787000]: info: Volumio Calling Home Feb 08 03:06:33 volumio go-librespot[1787003]: time="2026-02-08T03:06:33+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:33 volumio go-librespot[1787003]: time="2026-02-08T03:06:33+07:00" level=debug msg="completed challenge" Feb 08 03:06:34 volumio volumio[1787000]: info: Discovery: adding 46e269a1-2f26-4df8-b5f6-1749968c4324 Feb 08 03:06:34 volumio volumio[1787000]: info: Discovery: Found device Volumio Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:34 volumio volumio[1787000]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:34 volumio volumio[1787000]: info: MPD Permissions set Feb 08 03:06:34 volumio volumio[1787000]: info: MPD Permissions set Feb 08 03:06:34 volumio volumio[1787000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 08 03:06:34 volumio volumio[1787000]: info: Spotify config file written Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio sudo[1787171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 03:06:34 volumio sudo[1787171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:34 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 08 03:06:34 volumio systemd[1]: go-librespot-daemon.service: Killing process 1787006 (go-librespot) with signal SIGKILL. Feb 08 03:06:34 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 08 03:06:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 08 03:06:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio sudo[1787171]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:34 volumio go-librespot[1787177]: go-librespot daemon starting... Feb 08 03:06:34 volumio volumio[1787000]: info: Discovery: this is already registered, 46e269a1-2f26-4df8-b5f6-1749968c4324 Feb 08 03:06:34 volumio volumio[1787000]: info: Discovery: Found device Volumio Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:34 volumio volumio[1787000]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:34 volumio go-librespot[1787181]: time="2026-02-08T03:06:34+07:00" level=info msg="running go-librespot 0.6.2" Feb 08 03:06:34 volumio go-librespot[1787181]: time="2026-02-08T03:06:34+07:00" level=debug msg="app state loaded" Feb 08 03:06:34 volumio volumio-remote-updater[721]: [2026-02-08 03:06:34] [connect] Successful connection Feb 08 03:06:34 volumio go-librespot[1787181]: time="2026-02-08T03:06:34+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:34 volumio volumio[1787000]: info: No need to fix Spotify hosts Feb 08 03:06:34 volumio mpd[1787152]: decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 03:06:34 volumio mpd[1787152]: output: No 'audio_output' defined in config file Feb 08 03:06:34 volumio mpd[1787152]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for 0 Feb 08 03:06:34 volumio mpd[1787152]: couldn't open play stream: No such file or directory Feb 08 03:06:34 volumio mpd[1787152]: sndio_output: Error opening default sndio device Feb 08 03:06:34 volumio mpd[1787152]: output: Successfully detected a alsa audio device Feb 08 03:06:34 volumio mpd[1787152]: zeroconf: No global port, disabling zeroconf Feb 08 03:06:34 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 03:06:34 volumio sudo[1787120]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:34 volumio sudo[1787130]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:34 volumio volumio[1787000]: info: Volumio called home Feb 08 03:06:34 volumio volumio-remote-updater[721]: [2026-02-08 03:06:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770494794 101 Feb 08 03:06:34 volumio volumio[1787000]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Feb 08 03:06:34 volumio volumio[1787000]: info: Completed starting Core Plugins Feb 08 03:06:34 volumio volumio[1787000]: info: ------------------------------------------- Feb 08 03:06:34 volumio volumio[1787000]: info: ----- MyVolumio plugins startup ---- Feb 08 03:06:34 volumio volumio[1787000]: info: ------------------------------------------- Feb 08 03:06:34 volumio volumio[1787000]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 03:06:34 volumio volumio[1787000]: info: [jellyfin-poller] Polled http://192.168.1.202:8096: online Feb 08 03:06:34 volumio volumio[1787000]: info: Starting Shairport Sync Feb 08 03:06:34 volumio volumio[1787000]: info: Starting Shairport Sync Feb 08 03:06:34 volumio volumio[1787000]: info: Starting Shairport Sync Feb 08 03:06:34 volumio sudo[1787202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 03:06:34 volumio sudo[1787202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:34 volumio sudo[1787204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 03:06:34 volumio sudo[1787204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:34 volumio sudo[1787207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 03:06:34 volumio volumio[1787000]: error: MPD error: The expression evaluated to a falsy value: Feb 08 03:06:34 volumio volumio[1787000]: assert.ok(self.idling) Feb 08 03:06:34 volumio volumio[1787000]: error: The expression evaluated to a falsy value: Feb 08 03:06:34 volumio volumio[1787000]: assert.ok(self.idling) Feb 08 03:06:34 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 03:06:34 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 03:06:34 volumio sudo[1787207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:34 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:34 volumio systemd[1]: shairport-sync.service: Consumed 1.468s CPU time. Feb 08 03:06:34 volumio volumio[1787000]: info: MPD running with PID1787152 Feb 08 03:06:34 volumio volumio[1787000]: ,establishing connection Feb 08 03:06:34 volumio volumio[1787000]: error: updateQueue error: null Feb 08 03:06:34 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:34 volumio sudo[1787202]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:34 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 03:06:34 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 03:06:34 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:34 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:34 volumio sudo[1787204]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:34 volumio sudo[1787207]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:34 volumio volumio[1787000]: info: Shairport-Sync Started Feb 08 03:06:34 volumio volumio[1787000]: Error adding Membership: Error: addMembership EINVAL Feb 08 03:06:34 volumio volumio[1787000]: info: Shairport-Sync Started Feb 08 03:06:34 volumio volumio[1787000]: info: Shairport-Sync Started Feb 08 03:06:34 volumio volumio[1787000]: error: updateQueue error: null Feb 08 03:06:34 volumio volumio[1787000]: info: An error occurred while refreshing Spotify Token Error: Bad Request Feb 08 03:06:34 volumio volumio[1787000]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:34 volumio volumio[1787000]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:34 volumio go-librespot[1787181]: time="2026-02-08T03:06:34+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 08 03:06:34 volumio go-librespot[1787181]: time="2026-02-08T03:06:34+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 08 03:06:34 volumio go-librespot[1787181]: time="2026-02-08T03:06:34+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 08 03:06:34 volumio go-librespot[1787181]: time="2026-02-08T03:06:34+07:00" level=info msg="zeroconf server listening on port 42423" Feb 08 03:06:35 volumio go-librespot[1787181]: time="2026-02-08T03:06:35+07:00" level=debug msg="obtained new client token: AABxAoUcYNdc017X8I/ZL32VGjX6BvlDPWdULG1EQ6zQ8QNfythuzk5MKLVyP677QeLWMHOEN4tiatW7e3+kQ+nQWboGtsnI/5dB+MnYtjJ1wUhKi9S+nKPzDOMyfc9K25W7kSrXD9et8tRR93SqzeVWoWdZJAFSYl/K8//o/2vlP3n02c7WYltL/pQFPonxS2lblm8A8FcsjhnOQGs96fHh2HOkpRCPBO8fvFME+UgK4i8V+9Ja1vY=" Feb 08 03:06:35 volumio go-librespot[1787181]: time="2026-02-08T03:06:35+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 08 03:06:35 volumio go-librespot[1787181]: time="2026-02-08T03:06:35+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:35 volumio go-librespot[1787181]: time="2026-02-08T03:06:35+07:00" level=debug msg="completed challenge" Feb 08 03:06:35 volumio go-librespot[1787181]: time="2026-02-08T03:06:35+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:36 volumio go-librespot[1787181]: time="2026-02-08T03:06:36+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 08 03:06:36 volumio volumio[1787000]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:36 volumio volumio[1787000]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:36 volumio go-librespot[1787181]: time="2026-02-08T03:06:36+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:36 volumio go-librespot[1787181]: time="2026-02-08T03:06:36+07:00" level=debug msg="completed challenge" Feb 08 03:06:36 volumio go-librespot[1787181]: time="2026-02-08T03:06:36+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:36 volumio go-librespot[1787181]: time="2026-02-08T03:06:36+07:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 08 03:06:37 volumio go-librespot[1787181]: time="2026-02-08T03:06:37+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:37 volumio volumio[1787000]: info: go-librespot daemon successfully initialized Feb 08 03:06:37 volumio go-librespot[1787181]: time="2026-02-08T03:06:37+07:00" level=debug msg="completed challenge" Feb 08 03:06:37 volumio go-librespot[1787181]: time="2026-02-08T03:06:37+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:37 volumio go-librespot[1787181]: time="2026-02-08T03:06:37+07:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 08 03:06:38 volumio go-librespot[1787181]: time="2026-02-08T03:06:38+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:38 volumio go-librespot[1787181]: time="2026-02-08T03:06:38+07:00" level=debug msg="completed challenge" Feb 08 03:06:38 volumio go-librespot[1787181]: time="2026-02-08T03:06:38+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:39 volumio go-librespot[1787181]: time="2026-02-08T03:06:39+07:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 08 03:06:39 volumio mpd[1787152]: exception: No database Feb 08 03:06:39 volumio mpd[1787152]: exception: No database Feb 08 03:06:39 volumio mpd[1787152]: exception: No database Feb 08 03:06:39 volumio volumio[1787000]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Feb 08 03:06:39 volumio go-librespot[1787181]: time="2026-02-08T03:06:39+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:39 volumio go-librespot[1787181]: time="2026-02-08T03:06:39+07:00" level=debug msg="completed challenge" Feb 08 03:06:39 volumio go-librespot[1787181]: time="2026-02-08T03:06:39+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:40 volumio go-librespot[1787181]: time="2026-02-08T03:06:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 08 03:06:40 volumio volumio[1787000]: info: Initializing connection to go-librespot Websocket Feb 08 03:06:40 volumio volumio[1787000]: info: Connection to go-librespot Websocket established Feb 08 03:06:40 volumio go-librespot[1787181]: time="2026-02-08T03:06:40+07:00" level=debug msg="new websocket client" Feb 08 03:06:40 volumio go-librespot[1787181]: time="2026-02-08T03:06:40+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:40 volumio go-librespot[1787181]: time="2026-02-08T03:06:40+07:00" level=debug msg="completed challenge" Feb 08 03:06:41 volumio go-librespot[1787181]: time="2026-02-08T03:06:41+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:41 volumio go-librespot[1787181]: time="2026-02-08T03:06:41+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:41 volumio volumio[1787000]: info: Connection to go-librespot Websocket closed Feb 08 03:06:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 03:06:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 03:06:41 volumio sudo[1787250]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 03:06:41 volumio sudo[1787250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:41 volumio sudo[1787252]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 03:06:41 volumio sudo[1787252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:41 volumio sudo[1787250]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:41 volumio sudo[1787252]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:41 volumio sudo[1787258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 08 03:06:41 volumio sudo[1787258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:41 volumio sudo[1787258]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:41 volumio volumio[1787000]: info: Upmpdcli Daemon Started Feb 08 03:06:43 volumio volumio[1787000]: info: Getting Spotify volume Feb 08 03:06:43 volumio volumio[1787000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 03:06:43 volumio volumio[1787000]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 03:06:43 volumio volumio[1787000]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 08 03:06:43 volumio volumio[1787000]: errno: -111, Feb 08 03:06:43 volumio volumio[1787000]: code: 'ECONNREFUSED', Feb 08 03:06:43 volumio volumio[1787000]: syscall: 'connect', Feb 08 03:06:43 volumio volumio[1787000]: address: '127.0.0.1', Feb 08 03:06:43 volumio volumio[1787000]: port: 9879, Feb 08 03:06:43 volumio volumio[1787000]: response: undefined Feb 08 03:06:43 volumio volumio[1787000]: } Feb 08 03:06:43 volumio volumio[1787000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 03:06:43 volumio sudo[1787277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 03:05' Feb 08 03:06:43 volumio sudo[1787277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:43 volumio sudo[1787277]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:43 volumio volumio-remote-updater[721]: [2026-02-08 03:06:43] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 08 03:06:43 volumio volumio-remote-updater[721]: [2026-02-08 03:06:43] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 08 03:06:43 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 08 03:06:43 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 08 03:06:43 volumio systemd[1]: volumio.service: Consumed 3.251s CPU time. Feb 08 03:06:43 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 03:06:43 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 03:06:43 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5044. Feb 08 03:06:43 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 03:06:43 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 08 03:06:43 volumio systemd[1]: volumio.service: Consumed 3.251s CPU time. Feb 08 03:06:43 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 08 03:06:43 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 03:06:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 08 03:06:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:44 volumio go-librespot[1787304]: go-librespot daemon starting... Feb 08 03:06:44 volumio go-librespot[1787305]: time="2026-02-08T03:06:44+07:00" level=info msg="running go-librespot 0.6.2" Feb 08 03:06:44 volumio go-librespot[1787305]: time="2026-02-08T03:06:44+07:00" level=debug msg="app state loaded" Feb 08 03:06:44 volumio go-librespot[1787305]: time="2026-02-08T03:06:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 03:06:44 volumio volumio[1787289]: info: ------------------------------------------- Feb 08 03:06:44 volumio volumio[1787289]: info: ----- Volumio3 ---- Feb 08 03:06:44 volumio volumio[1787289]: info: ------------------------------------------- Feb 08 03:06:44 volumio volumio[1787289]: info: ----- System startup ---- Feb 08 03:06:44 volumio volumio[1787289]: info: ------------------------------------------- Feb 08 03:06:44 volumio go-librespot[1787305]: time="2026-02-08T03:06:44+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 08 03:06:44 volumio go-librespot[1787305]: time="2026-02-08T03:06:44+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 08 03:06:44 volumio go-librespot[1787305]: time="2026-02-08T03:06:44+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 08 03:06:44 volumio go-librespot[1787305]: time="2026-02-08T03:06:44+07:00" level=info msg="zeroconf server listening on port 39171" Feb 08 03:06:44 volumio volumio[1787289]: info: MYVOLUMIO Environment detected Feb 08 03:06:44 volumio volumio[1787289]: info: Plugin folders cleanup Feb 08 03:06:44 volumio volumio[1787289]: info: Scanning into folder /volumio/app/plugins/ Feb 08 03:06:44 volumio volumio[1787289]: info: Scanning category audio_interface Feb 08 03:06:44 volumio volumio[1787289]: info: Scanning category miscellanea Feb 08 03:06:44 volumio volumio[1787289]: info: Scanning category music_service Feb 08 03:06:44 volumio volumio[1787289]: info: Scanning category plugins.json Feb 08 03:06:44 volumio volumio[1787289]: info: Scanning category system_controller Feb 08 03:06:44 volumio volumio[1787289]: info: Scanning category user_interface Feb 08 03:06:44 volumio volumio[1787289]: info: Scanning into folder /data/plugins/ Feb 08 03:06:44 volumio volumio[1787289]: info: Scanning category music_service Feb 08 03:06:44 volumio volumio[1787289]: info: Plugin folders cleanup completed Feb 08 03:06:44 volumio volumio[1787289]: info: ------------------------------------------- Feb 08 03:06:44 volumio volumio[1787289]: info: ----- Core plugins startup ---- Feb 08 03:06:44 volumio volumio[1787289]: info: ------------------------------------------- Feb 08 03:06:44 volumio volumio[1787289]: info: Loading plugins from folder /volumio/app/plugins/ Feb 08 03:06:44 volumio volumio[1787289]: info: Adding plugin upnp to MyMusic Plugins Feb 08 03:06:44 volumio volumio[1787289]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 08 03:06:44 volumio volumio[1787289]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 08 03:06:44 volumio volumio[1787289]: info: Loading plugins from folder /data/plugins/ Feb 08 03:06:44 volumio volumio[1787289]: info: Loading plugin "system"... Feb 08 03:06:44 volumio volumio[1787289]: info: Loading plugin "appearance"... Feb 08 03:06:45 volumio go-librespot[1787305]: time="2026-02-08T03:06:45+07:00" level=debug msg="obtained new client token: AABMBWKgEM34nLBNYky9YmS1ZW5QrZF/65ZdHGZ3OagOxs9CLoCIyryRAZFGbvnVOk2klfenFMbMoGXkC9T2mSR5ThvZ656KljSe8Okvti4rx7IAfGz0h/fww7LQAfF5Sc9JEsnmgq54o6qieIkUKD865Ruz1j5lCGNLpUCqt0U/J2QIL4QdaMqZYmbNlJ7mXIZenmk94I6jGqnNNOUcji3zOMs/QW/UTTRyofrc6AYHtV4yq0inaTM=" Feb 08 03:06:45 volumio go-librespot[1787305]: time="2026-02-08T03:06:45+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "network"... Feb 08 03:06:45 volumio volumio[1787289]: info: Refreshing Cached IP Addresses Feb 08 03:06:45 volumio sudo[1787327]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "services"... Feb 08 03:06:45 volumio sudo[1787327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "alsa_controller"... Feb 08 03:06:45 volumio sudo[1787327]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:45 volumio sudo[1787329]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 03:06:45 volumio sudo[1787329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:45 volumio sudo[1787329]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:45 volumio sudo[1787333]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 08 03:06:45 volumio sudo[1787333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:45 volumio sudo[1787333]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:45 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "wizard"... Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "networkfs"... Feb 08 03:06:45 volumio volumio[1787289]: info: Starting Udev Watcher for removable devices Feb 08 03:06:45 volumio volumio[1787289]: info: Ignoring mount for partition: boot Feb 08 03:06:45 volumio volumio[1787289]: info: Ignoring mount for partition: volumio Feb 08 03:06:45 volumio volumio[1787289]: info: Ignoring mount for partition: volumio_data Feb 08 03:06:45 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "volumio_command_line_client"... Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "upnp"... Feb 08 03:06:45 volumio volumio[1787289]: info: [1770494805387] Starting Upmpd Daemon Feb 08 03:06:45 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "my_music"... Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "mpd"... Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "upnp_browser"... Feb 08 03:06:45 volumio go-librespot[1787305]: time="2026-02-08T03:06:45+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:45 volumio go-librespot[1787305]: time="2026-02-08T03:06:45+07:00" level=debug msg="completed challenge" Feb 08 03:06:45 volumio go-librespot[1787305]: time="2026-02-08T03:06:45+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:45 volumio volumio[1787289]: info: Starting UPNP Browser Feb 08 03:06:45 volumio volumio[1787289]: info: Loading plugin "alarm-clock"... Feb 08 03:06:46 volumio volumio[1787289]: info: Loading plugin "airplay_emulation"... Feb 08 03:06:46 volumio volumio[1787289]: info: Starting Shairport Sync Feb 08 03:06:46 volumio volumio[1787289]: info: Loading plugin "last_100"... Feb 08 03:06:46 volumio volumio[1787289]: info: Loading plugin "webradio"... Feb 08 03:06:46 volumio volumio[1787289]: info: Loading plugin "i2s_dacs"... Feb 08 03:06:46 volumio volumio[1787289]: info: I2S DAC not set, start Auto-detection Feb 08 03:06:46 volumio volumio[1787289]: info: Loading plugin "volumiodiscovery"... Feb 08 03:06:46 volumio volumio[1787289]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:46 volumio node[1787289]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:46 volumio volumio[1787289]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:46 volumio node[1787289]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:46 volumio volumio[1787289]: *** WARNING *** For more information see Feb 08 03:06:46 volumio node[1787289]: *** WARNING *** For more information see Feb 08 03:06:46 volumio volumio[1787289]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:46 volumio node[1787289]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 03:06:46 volumio volumio[1787289]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:46 volumio node[1787289]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 03:06:46 volumio volumio[1787289]: *** WARNING *** For more information see Feb 08 03:06:46 volumio node[1787289]: *** WARNING *** For more information see Feb 08 03:06:46 volumio volumio[1787289]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 08 03:06:46 volumio volumio[1787289]: info: Discovery: Started advertising with name: Volumio Feb 08 03:06:46 volumio go-librespot[1787305]: time="2026-02-08T03:06:46+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 08 03:06:46 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:46 volumio volumio[1787289]: info: Loading plugin "jellyfin"... Feb 08 03:06:46 volumio go-librespot[1787305]: time="2026-02-08T03:06:46+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:46 volumio go-librespot[1787305]: time="2026-02-08T03:06:46+07:00" level=debug msg="completed challenge" Feb 08 03:06:46 volumio volumio[1787289]: info: Loading plugin "spop"... Feb 08 03:06:46 volumio go-librespot[1787305]: time="2026-02-08T03:06:46+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:46 volumio volumio[1787289]: info: Loading plugin "youtube2"... Feb 08 03:06:47 volumio volumio[1787289]: info: Loading plugin "ytmusic"... Feb 08 03:06:47 volumio go-librespot[1787305]: time="2026-02-08T03:06:47+07:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 08 03:06:47 volumio volumio[1787289]: info: Loading plugin "outputs"... Feb 08 03:06:47 volumio volumio[1787289]: info: Loading plugin "albumart"... Feb 08 03:06:47 volumio volumio[1787289]: info: Plugin example_plugin is not enabled Feb 08 03:06:47 volumio volumio[1787289]: info: Loading plugin "inputs"... Feb 08 03:06:47 volumio volumio[1787289]: info: Loading plugin "updater_comm"... Feb 08 03:06:47 volumio volumio[1787289]: info: Plugin mpdemulation is not enabled Feb 08 03:06:47 volumio volumio[1787289]: info: Loading plugin "rest_api"... Feb 08 03:06:47 volumio volumio[1787289]: info: Loading plugin "websocket"... Feb 08 03:06:47 volumio volumio[1787289]: info: Starting Socket.io Server version 1.7.4 Feb 08 03:06:47 volumio volumio[1787289]: info: Loading i18n strings for locale ru Feb 08 03:06:47 volumio volumio[1787289]: Updating browse sources language Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:47 volumio volumio[1787368]: Forking 1 albumart workers Feb 08 03:06:47 volumio go-librespot[1787305]: time="2026-02-08T03:06:47+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:47 volumio go-librespot[1787305]: time="2026-02-08T03:06:47+07:00" level=debug msg="completed challenge" Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::initPlayerControls Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: Express server listening on port 3000 Feb 08 03:06:47 volumio volumio[1787289]: [Metrics] WebUI: 3s 233.84ms Feb 08 03:06:47 volumio volumio[1787289]: info: Setting Device type: x86 Feb 08 03:06:47 volumio volumio[1787289]: info: CoreStateMachine::resetVolumioState Feb 08 03:06:47 volumio volumio[1787289]: info: CoreStateMachine::getcurrentVolume Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:47 volumio go-librespot[1787305]: time="2026-02-08T03:06:47+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:47 volumio volumio[1787289]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 08 03:06:47 volumio volumio[1787289]: info: Completed loading Core Plugins Feb 08 03:06:47 volumio volumio[1787289]: info: Preparing to generate the ALSA configuration file Feb 08 03:06:47 volumio volumio[1787289]: info: Volumio Network Manager: Network status updated: 1 Feb 08 03:06:47 volumio volumio[1787289]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 08 03:06:47 volumio volumio[1787289]: wlan0 Interface doesn't support scanning. Feb 08 03:06:47 volumio volumio[1787289]: info: Cannot use regular scanning, forcing with ap-force Feb 08 03:06:47 volumio sudo[1787393]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 08 03:06:47 volumio sudo[1787393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:47 volumio sudo[1787393]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:47 volumio volumio[1787289]: command failed: No such device (-19) Feb 08 03:06:47 volumio volumio[1787289]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 08 03:06:47 volumio volumio[1787289]: command failed: No such device (-19) Feb 08 03:06:47 volumio volumio[1787289]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 08 03:06:47 volumio volumio[1787289]: info: VolumeController:: Volume=48 Mute =false Feb 08 03:06:47 volumio volumio[1787289]: info: CoreStateMachine::pushState Feb 08 03:06:47 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:47 volumio volumio[1787289]: info: CoreStateMachine::updateTrackBlock Feb 08 03:06:47 volumio volumio[1787289]: info: CorePlayQueue::getTrackBlock Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:47 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:47 volumio volumio[1787289]: info: Reloading queue from file Feb 08 03:06:47 volumio volumio[1787289]: info: VolumeController:: Volume=48 Mute =false Feb 08 03:06:47 volumio volumio[1787289]: info: CoreStateMachine::pushState Feb 08 03:06:47 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:47 volumio volumio[1787289]: info: CoreStateMachine::setRepeat null single undefined Feb 08 03:06:47 volumio volumio[1787289]: info: CoreStateMachine::pushState Feb 08 03:06:47 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:47 volumio volumio[1787289]: info: CoreStateMachine::setRandom null Feb 08 03:06:47 volumio volumio[1787289]: info: CoreStateMachine::pushState Feb 08 03:06:47 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::volumioPushState Feb 08 03:06:47 volumio volumio[1787289]: info: Listing playlists Feb 08 03:06:47 volumio volumio[1787289]: info: Listing playlists Feb 08 03:06:47 volumio volumio[1787289]: info: Asound.conf file unchanged, so no further update is needed Feb 08 03:06:47 volumio volumio[1787289]: info: Output device has changed, restarting MPD Feb 08 03:06:47 volumio volumio[1787289]: info: Output device has changed, restarting Shairport Sync Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:47 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:47 volumio sudo[1787405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 03:06:47 volumio sudo[1787405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:47 volumio sudo[1787405]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:47 volumio volumio[1787289]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 03:06:47 volumio volumio[1787289]: info: ___________ START PLUGINS ___________ Feb 08 03:06:47 volumio sudo[1787407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 03:06:47 volumio sudo[1787407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:47 volumio volumio[1787289]: info: ControllerMpd::onStart: Initializing MPD Feb 08 03:06:47 volumio volumio[1787289]: info: Creating MPD Configuration file Feb 08 03:06:48 volumio volumio[1787379]: Starting albumart workers Feb 08 03:06:48 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:48 volumio sudo[1787415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 03:06:48 volumio volumio[1787289]: info: [1770494808035] CoreMusicLibrary::Adding element Медиа-серверы Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:48 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 03:06:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 03:06:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 03:06:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 03:06:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 03:06:48 volumio go-librespot[1787305]: time="2026-02-08T03:06:48+07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 03:06:48 volumio volumio[1787289]: info: UPNP Browser: Client initialized successfully Feb 08 03:06:48 volumio sudo[1787415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:48 volumio sudo[1787417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 03:06:48 volumio sudo[1787417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 03:06:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 03:06:48 volumio sudo[1787415]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:48 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 03:06:48 volumio volumio[1787289]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 03:06:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 03:06:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 03:06:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 03:06:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 03:06:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 03:06:48 volumio volumio[1787289]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:48 volumio volumio[1787289]: info: [1770494808130] CoreMusicLibrary::Adding element Last_100 Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:48 volumio volumio[1787289]: info: [1770494808133] CoreMusicLibrary::Adding element Webradio Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 03:06:48 volumio volumio[1787289]: info: Initializing BBC Radios Feb 08 03:06:48 volumio sudo[1787431]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 03:06:48 volumio sudo[1787431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 03:06:48 volumio sudo[1787431]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 08 03:06:48 volumio volumio[1787289]: info: Discovery: Getting this device information Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:48 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:48 volumio volumio[1787289]: info: [1770494808256] CoreMusicLibrary::Adding element Jellyfin Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:48 volumio volumio[1787289]: Cannot find translation for source Jellyfin Feb 08 03:06:48 volumio volumio[1787289]: info: [jellyfin] Initialized plugin with device info: {"host":"http://192.168.1.133","id":"46e269a1-2f26-4df8-b5f6-1749968c4324","name":"Volumio","serviceName":"Volumio","state":{"albumart":"https://i.scdn.co/image/ab67616d0000b2732f2c4ed053b2399461163048","artist":"Ozma","mute":false,"status":"stop","track":"Эхо","volume":48},"type":"device"} Feb 08 03:06:48 volumio volumio[1787289]: info: Creating Spotify config file Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:48 volumio volumio[1787289]: info: [1770494808465] CoreMusicLibrary::Adding element YouTube2 Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:48 volumio volumio[1787289]: Cannot find translation for source Jellyfin Feb 08 03:06:48 volumio volumio[1787289]: Cannot find translation for source YouTube2 Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 03:06:48 volumio volumio[1787289]: info: [1770494808489] CoreMusicLibrary::Adding element YouTube Music Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 03:06:48 volumio volumio[1787289]: Cannot find translation for source Jellyfin Feb 08 03:06:48 volumio volumio[1787289]: Cannot find translation for source YouTube2 Feb 08 03:06:48 volumio volumio[1787289]: Cannot find translation for source YouTube Music Feb 08 03:06:48 volumio volumio[1787289]: info: Volumio Calling Home Feb 08 03:06:48 volumio go-librespot[1787305]: time="2026-02-08T03:06:48+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:48 volumio go-librespot[1787305]: time="2026-02-08T03:06:48+07:00" level=debug msg="completed challenge" Feb 08 03:06:48 volumio volumio-remote-updater[721]: [2026-02-08 03:06:48] [connect] Successful connection Feb 08 03:06:48 volumio volumio[1787289]: info: MPD Permissions set Feb 08 03:06:48 volumio volumio[1787289]: info: MPD Permissions set Feb 08 03:06:48 volumio volumio[1787289]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 08 03:06:48 volumio volumio[1787289]: info: Spotify config file written Feb 08 03:06:48 volumio volumio[1787289]: info: Discovery: adding 46e269a1-2f26-4df8-b5f6-1749968c4324 Feb 08 03:06:48 volumio volumio[1787289]: info: Discovery: Found device Volumio Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:48 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:48 volumio sudo[1787459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 03:06:48 volumio sudo[1787459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:48 volumio volumio-remote-updater[721]: [2026-02-08 03:06:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770494808 101 Feb 08 03:06:48 volumio volumio[1787289]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Feb 08 03:06:48 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 08 03:06:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 03:06:48 volumio go-librespot[1787462]: go-librespot daemon starting... Feb 08 03:06:48 volumio sudo[1787459]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 08 03:06:48 volumio mpd[1787441]: 2026-02-08T03:06:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 03:06:48 volumio go-librespot[1787467]: time="2026-02-08T03:06:48+07:00" level=info msg="running go-librespot 0.6.2" Feb 08 03:06:48 volumio go-librespot[1787467]: time="2026-02-08T03:06:48+07:00" level=debug msg="app state loaded" Feb 08 03:06:48 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 03:06:48 volumio sudo[1787407]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:48 volumio go-librespot[1787467]: time="2026-02-08T03:06:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio sudo[1787417]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 03:06:48 volumio volumio[1787289]: info: No need to fix Spotify hosts Feb 08 03:06:48 volumio volumio[1787289]: info: Discovery: this is already registered, 46e269a1-2f26-4df8-b5f6-1749968c4324 Feb 08 03:06:48 volumio volumio[1787289]: info: Discovery: Found device Volumio Feb 08 03:06:48 volumio volumio[1787289]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:48 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:48 volumio volumio[1787289]: info: Volumio called home Feb 08 03:06:48 volumio volumio[1787289]: info: Completed starting Core Plugins Feb 08 03:06:48 volumio volumio[1787289]: info: ------------------------------------------- Feb 08 03:06:48 volumio volumio[1787289]: info: ----- MyVolumio plugins startup ---- Feb 08 03:06:48 volumio volumio[1787289]: info: ------------------------------------------- Feb 08 03:06:48 volumio volumio[1787289]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 03:06:48 volumio volumio[1787289]: info: [jellyfin-poller] Polled http://192.168.1.202:8096: online Feb 08 03:06:48 volumio volumio[1787289]: info: Starting Shairport Sync Feb 08 03:06:48 volumio volumio[1787289]: info: Starting Shairport Sync Feb 08 03:06:48 volumio volumio[1787289]: info: Starting Shairport Sync Feb 08 03:06:48 volumio sudo[1787492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 03:06:48 volumio sudo[1787492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:48 volumio sudo[1787494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 03:06:48 volumio sudo[1787494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:48 volumio sudo[1787491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 03:06:48 volumio sudo[1787491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 03:06:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 03:06:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:49 volumio volumio[1787289]: error: MPD error: The expression evaluated to a falsy value: Feb 08 03:06:49 volumio volumio[1787289]: assert.ok(self.idling) Feb 08 03:06:49 volumio volumio[1787289]: error: The expression evaluated to a falsy value: Feb 08 03:06:49 volumio volumio[1787289]: assert.ok(self.idling) Feb 08 03:06:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:49 volumio volumio[1787289]: info: MPD running with PID1787441 Feb 08 03:06:49 volumio volumio[1787289]: ,establishing connection Feb 08 03:06:49 volumio sudo[1787492]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:49 volumio volumio[1787289]: error: updateQueue error: null Feb 08 03:06:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 03:06:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 03:06:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:49 volumio sudo[1787491]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 03:06:49 volumio sudo[1787494]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:49 volumio volumio[1787289]: info: Shairport-Sync Started Feb 08 03:06:49 volumio volumio[1787289]: Error adding Membership: Error: addMembership EINVAL Feb 08 03:06:49 volumio volumio[1787289]: info: Shairport-Sync Started Feb 08 03:06:49 volumio volumio[1787289]: info: Shairport-Sync Started Feb 08 03:06:49 volumio volumio[1787289]: error: updateQueue error: null Feb 08 03:06:49 volumio volumio[1787289]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:49 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:49 volumio volumio[1787289]: info: An error occurred while refreshing Spotify Token Error: Bad Request Feb 08 03:06:49 volumio go-librespot[1787467]: time="2026-02-08T03:06:49+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 08 03:06:49 volumio go-librespot[1787467]: time="2026-02-08T03:06:49+07:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 08 03:06:49 volumio go-librespot[1787467]: time="2026-02-08T03:06:49+07:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 08 03:06:49 volumio go-librespot[1787467]: time="2026-02-08T03:06:49+07:00" level=info msg="zeroconf server listening on port 45155" Feb 08 03:06:49 volumio go-librespot[1787467]: time="2026-02-08T03:06:49+07:00" level=debug msg="obtained new client token: AADgPSQ0cTis3Htx29X3ffLAnUSQKJmCges2y2/O4t+JVWPt4nkw8nXs8ZGWWKWXHwVk/CliC//zOY85QxVwsLxiuvVm4Suou0iZaBGNEh+5tGm4xe7pbGhU6pKsgI+8SxkSDAGeotO2Hi2l5oWYtzSTgPTZEITMKHnaX/KWbEuEnqLEz3HNIUQGDsdjJUv39CXL9N/ffopdeKd6BNRMDSAmlN/OS84vBxs5DIA/I93/U52Ac4/RLC2nKA==" Feb 08 03:06:49 volumio go-librespot[1787467]: time="2026-02-08T03:06:49+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 08 03:06:50 volumio go-librespot[1787467]: time="2026-02-08T03:06:50+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:50 volumio go-librespot[1787467]: time="2026-02-08T03:06:50+07:00" level=debug msg="completed challenge" Feb 08 03:06:50 volumio go-librespot[1787467]: time="2026-02-08T03:06:50+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:50 volumio go-librespot[1787467]: time="2026-02-08T03:06:50+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 08 03:06:50 volumio go-librespot[1787467]: time="2026-02-08T03:06:50+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:50 volumio go-librespot[1787467]: time="2026-02-08T03:06:50+07:00" level=debug msg="completed challenge" Feb 08 03:06:51 volumio go-librespot[1787467]: time="2026-02-08T03:06:51+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:51 volumio go-librespot[1787467]: time="2026-02-08T03:06:51+07:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 08 03:06:51 volumio volumio[1787289]: info: go-librespot daemon successfully initialized Feb 08 03:06:52 volumio go-librespot[1787467]: time="2026-02-08T03:06:52+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:52 volumio go-librespot[1787467]: time="2026-02-08T03:06:52+07:00" level=debug msg="completed challenge" Feb 08 03:06:52 volumio go-librespot[1787467]: time="2026-02-08T03:06:52+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:52 volumio go-librespot[1787467]: time="2026-02-08T03:06:52+07:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 08 03:06:53 volumio go-librespot[1787467]: time="2026-02-08T03:06:53+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:53 volumio go-librespot[1787467]: time="2026-02-08T03:06:53+07:00" level=debug msg="completed challenge" Feb 08 03:06:53 volumio go-librespot[1787467]: time="2026-02-08T03:06:53+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:53 volumio volumio[1787289]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 08 03:06:54 volumio go-librespot[1787467]: time="2026-02-08T03:06:54+07:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 08 03:06:54 volumio volumio[1787289]: info: Initializing connection to go-librespot Websocket Feb 08 03:06:54 volumio go-librespot[1787467]: time="2026-02-08T03:06:54+07:00" level=debug msg="new websocket client" Feb 08 03:06:54 volumio volumio[1787289]: info: Connection to go-librespot Websocket established Feb 08 03:06:55 volumio sudo[1787539]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 03:06:55 volumio sudo[1787539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:55 volumio sudo[1787539]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:55 volumio sudo[1787541]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 03:06:55 volumio sudo[1787541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:55 volumio sudo[1787541]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:55 volumio sudo[1787547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 08 03:06:55 volumio sudo[1787547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:55 volumio sudo[1787547]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:55 volumio volumio[1787289]: info: Upmpdcli Daemon Started Feb 08 03:06:56 volumio volumio[1787289]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:56 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 08 03:06:57 volumio volumio[1787289]: info: Adding plugin bluetooth to MyMusic Plugins Feb 08 03:06:57 volumio volumio[1787289]: info: Adding plugin multiroom to MyMusic Plugins Feb 08 03:06:57 volumio volumio[1787289]: info: Adding plugin metavolumio to MyMusic Plugins Feb 08 03:06:57 volumio volumio[1787289]: info: Adding plugin cd_controller to MyMusic Plugins Feb 08 03:06:57 volumio volumio[1787289]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 08 03:06:57 volumio volumio[1787289]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 08 03:06:57 volumio volumio[1787289]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 08 03:06:57 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:57 volumio volumio[1787289]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 03:06:57 volumio volumio[1787289]: info: Starting MyVolumio Remote Streaming Endpoints Feb 08 03:06:57 volumio volumio[1787289]: info: MyVolumio login type: Token Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 08 03:06:57 volumio volumio[1787289]: info: Streaming services startup Feb 08 03:06:57 volumio volumio[1787289]: info: Starting Streaming Daemon Feb 08 03:06:57 volumio volumio[1787289]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 08 03:06:57 volumio sudo[1787552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 08 03:06:57 volumio sudo[1787552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:06:57 volumio sudo[1787552]: pam_unix(sudo:session): session closed for user root Feb 08 03:06:57 volumio volumio[1787289]: info: Getting Spotify volume Feb 08 03:06:57 volumio volumio[1787289]: error: Cannot start Volumio Streaming Daemon Feb 08 03:06:57 volumio volumio[1787289]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 08 03:06:57 volumio volumio[1787289]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 08 03:06:57 volumio volumio[1787289]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 08 03:06:58 volumio volumio[1787289]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:58 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:58 volumio volumio[1787289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Feb 08 03:06:58 volumio volumio[1787289]: SPOTIFY: SPOTIFY VOLUME undefined Feb 08 03:06:58 volumio volumio[1787289]: SPOTIFY: VOLUMIO VOLUME 48 Feb 08 03:06:58 volumio volumio[1787289]: info: Aligning Spotify Volume to Volumio Volume Feb 08 03:06:58 volumio volumio[1787289]: info: CoreCommandRouter::volumioGetState Feb 08 03:06:58 volumio volumio[1787289]: info: CorePlayQueue::getTrack 0 Feb 08 03:06:58 volumio volumio[1787289]: info: Setting Spotify Volume from Volumio: 48 Feb 08 03:06:59 volumio volumio[1787289]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 08 03:06:59 volumio go-librespot[1787467]: time="2026-02-08T03:06:59+07:00" level=debug msg="completed keyexchange" Feb 08 03:06:59 volumio go-librespot[1787467]: time="2026-02-08T03:06:59+07:00" level=debug msg="completed challenge" Feb 08 03:06:59 volumio go-librespot[1787467]: time="2026-02-08T03:06:59+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:06:59 volumio volumio[1787289]: SPOTIFY: SETTING SPOTIFY VOLUME 48 Feb 08 03:06:59 volumio volumio[1787289]: info: Sending Spotify command with payload to local API: /player/volume Feb 08 03:06:59 volumio volumio[1787289]: info: MyVolumio token set successfully Feb 08 03:06:59 volumio volumio[1787289]: info: MYVOLUMIO: Adding device Feb 08 03:06:59 volumio volumio[1787289]: info: MYVOLUMIO: Evaluating Server Feb 08 03:07:00 volumio go-librespot[1787467]: time="2026-02-08T03:07:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 08 03:07:00 volumio volumio[1787289]: info: MyVolumio status changed Feb 08 03:07:00 volumio volumio[1787289]: info: Streaming services startup Feb 08 03:07:00 volumio volumio[1787289]: info: Starting Streaming Daemon Feb 08 03:07:00 volumio volumio[1787289]: info: Removing browser output: myVolumio user plan is not superstar Feb 08 03:07:00 volumio volumio[1787289]: info: Removing audio output: Feb 08 03:07:00 volumio volumio[1787289]: info: Stoppping Tunnel 1 Feb 08 03:07:00 volumio sudo[1787581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 08 03:07:00 volumio sudo[1787581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:07:00 volumio sudo[1787584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 08 03:07:00 volumio sudo[1787584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 03:07:00 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:07:00 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:07:00 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:07:00 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:07:00 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:07:00 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:07:00 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:07:00 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 08 03:07:00 volumio sudo[1787584]: pam_unix(sudo:session): session closed for user root Feb 08 03:07:00 volumio volumio[1787289]: info: Remote SSH Stopped Feb 08 03:07:00 volumio sudo[1787581]: pam_unix(sudo:session): session closed for user root Feb 08 03:07:00 volumio volumio[1787289]: error: Cannot start Volumio Streaming Daemon Feb 08 03:07:00 volumio volumio[1787289]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 08 03:07:00 volumio volumio[1787289]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 08 03:07:00 volumio go-librespot[1787467]: time="2026-02-08T03:07:00+07:00" level=debug msg="completed keyexchange" Feb 08 03:07:00 volumio go-librespot[1787467]: time="2026-02-08T03:07:00+07:00" level=debug msg="completed challenge" Feb 08 03:07:01 volumio go-librespot[1787467]: time="2026-02-08T03:07:01+07:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:07:01 volumio go-librespot[1787467]: time="2026-02-08T03:07:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 03:07:01 volumio volumio[1787289]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Feb 08 03:07:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 03:07:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 03:07:01 volumio volumio[1787289]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 03:07:01 volumio volumio[1787289]: Error: socket hang up Feb 08 03:07:01 volumio volumio[1787289]: at connResetException (node:internal/errors:720:14) Feb 08 03:07:01 volumio volumio[1787289]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 08 03:07:01 volumio volumio[1787289]: at Socket.emit (node:events:526:35) Feb 08 03:07:01 volumio volumio[1787289]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 08 03:07:01 volumio volumio[1787289]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 08 03:07:01 volumio volumio[1787289]: code: 'ECONNRESET', Feb 08 03:07:01 volumio volumio[1787289]: response: undefined Feb 08 03:07:01 volumio volumio[1787289]: } Feb 08 03:07:01 volumio volumio[1787289]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 03:07:01 volumio sudo[1787601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 03:06' Feb 08 03:07:01 volumio sudo[1787601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"