Nov 18 00:43:00 volumio sudo[6406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Nov 18 00:43:00 volumio sudo[6406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:00 volumio systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). Nov 18 00:43:00 volumio sudo[6406]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:00 volumio lircd[6399]: lircd-0.10.1[6399]: Notice: accepted new client on /var/run/lirc/lircd Nov 18 00:43:00 volumio lircd-0.10.1[6399]: Notice: accepted new client on /var/run/lirc/lircd Nov 18 00:43:00 volumio lircd[6399]: lircd-0.10.1[6399]: Info: [lirc] protocol is enabled Nov 18 00:43:00 volumio lircd-0.10.1[6399]: Info: [lirc] protocol is enabled Nov 18 00:43:00 volumio volumio[6076]: info: ir_controller: systemctl restart irexec.service succeeded. Nov 18 00:43:02 volumio sudo[6411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Nov 18 00:43:02 volumio sudo[6411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:02 volumio sudo[6413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Nov 18 00:43:02 volumio sudo[6413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:02 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Nov 18 00:43:02 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Nov 18 00:43:02 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Nov 18 00:43:02 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Nov 18 00:43:02 volumio sudo[6413]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:02 volumio sudo[6411]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:02 volumio mpd_monitor.sh[6416]: MPD Monitor Service: Starting MPD Monitor Service Nov 18 00:43:02 volumio volumio[6076]: info: Successfully started MPD Monitor Nov 18 00:43:02 volumio volumio[6076]: info: Successfully started MPD Monitor Nov 18 00:43:02 volumio volumio[6076]: info: go-librespot daemon successfully initialized Nov 18 00:43:04 volumio volumio[6076]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 18 00:43:04 volumio bash[5990]: setdatetime-helper: all HTTPS Date fallbacks failed Nov 18 00:43:04 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Nov 18 00:43:04 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Nov 18 00:43:05 volumio volumio[6076]: info: Initializing connection to go-librespot Websocket Nov 18 00:43:05 volumio go-librespot[6374]: time="2025-11-18T00:43:05+08:00" level=debug msg="new websocket client" Nov 18 00:43:05 volumio volumio[6076]: info: Connection to go-librespot Websocket established Nov 18 00:43:06 volumio sudo[6439]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 18 00:43:06 volumio sudo[6437]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 18 00:43:06 volumio sudo[6439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:06 volumio sudo[6437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:06 volumio sudo[6439]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:06 volumio sudo[6437]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:06 volumio sudo[6441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 18 00:43:06 volumio sudo[6441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:06 volumio sudo[6441]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:06 volumio volumio[6076]: info: Upmpdcli Daemon Started Nov 18 00:43:08 volumio volumio[6076]: info: Getting Spotify volume Nov 18 00:43:08 volumio volumio[6076]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 18 00:43:08 volumio volumio[6076]: info: CoreCommandRouter::volumioGetState Nov 18 00:43:08 volumio volumio[6076]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:18 volumio volumio[6076]: info: AutoStart - Plugin is starting Nov 18 00:43:18 volumio volumio[6076]: info: CoreCommandRouter::volumioGetQueue Nov 18 00:43:18 volumio volumio[6076]: info: CoreStateMachine::getQueue Nov 18 00:43:18 volumio volumio[6076]: info: CorePlayQueue::getQueue Nov 18 00:43:18 volumio volumio[6076]: info: AutoStart - start playing Nov 18 00:43:18 volumio volumio[6076]: info: AutoStart - start playing with no specific position Nov 18 00:43:18 volumio volumio[6076]: info: CoreCommandRouter::volumioPlay Nov 18 00:43:18 volumio volumio[6076]: info: CoreStateMachine::play index 0 Nov 18 00:43:18 volumio volumio[6076]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 18 00:43:18 volumio volumio[6076]: info: CoreStateMachine::stop Nov 18 00:43:18 volumio volumio[6076]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 18 00:43:18 volumio volumio[6076]: info: CoreStateMachine::play index undefined Nov 18 00:43:18 volumio volumio[6076]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 18 00:43:18 volumio volumio[6076]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:18 volumio volumio[6076]: info: CoreStateMachine::startPlaybackTimer Nov 18 00:43:18 volumio volumio[6076]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:18 volumio volumio[6076]: verbose: ControllerMpd::clearAddPlayTracks USB/CB78-A5DB/徐小明-撼江湖2 HQII/01.男儿当自强.wav Nov 18 00:43:18 volumio volumio[6076]: verbose: ControllerMpd::sendMpdCommand stop Nov 18 00:43:18 volumio volumio[6076]: info: sendMpdCommand stop took 1 milliseconds Nov 18 00:43:18 volumio volumio[6076]: verbose: ControllerMpd::sendMpdCommand clear Nov 18 00:43:18 volumio volumio[6076]: info: Nov 18 00:43:18 volumio volumio[6076]: ---------------------------- MPD announces system playlist update Nov 18 00:43:18 volumio volumio[6076]: info: Ignoring MPD Status Update Nov 18 00:43:18 volumio volumio[6076]: info: sendMpdCommand clear took 1 milliseconds Nov 18 00:43:18 volumio volumio[6076]: verbose: ControllerMpd::sendMpdCommand add "USB/CB78-A5DB/徐小明-撼江湖2 HQII/01.男儿当自强.wav" Nov 18 00:43:18 volumio volumio[6076]: info: Nov 18 00:43:18 volumio volumio[6076]: ---------------------------- MPD announces system playlist update Nov 18 00:43:18 volumio volumio[6076]: info: Ignoring MPD Status Update Nov 18 00:43:18 volumio volumio[6076]: info: Nov 18 00:43:18 volumio volumio[6076]: ---------------------------- MPD announces system playlist update Nov 18 00:43:18 volumio volumio[6076]: info: Ignoring MPD Status Update Nov 18 00:43:18 volumio volumio[6076]: error: updateQueue error: null Nov 18 00:43:18 volumio volumio[6076]: error: updateQueue error: null Nov 18 00:43:18 volumio volumio[6076]: error: updateQueue error: null Nov 18 00:43:18 volumio volumio[6076]: info: ------------------------------ 2ms Nov 18 00:43:18 volumio volumio[6076]: info: ------------------------------ 2ms Nov 18 00:43:18 volumio volumio[6076]: info: ------------------------------ 1ms Nov 18 00:43:19 volumio volumio[6076]: error: MyVolumio Plugin failed to start in a timely fashion Nov 18 00:43:19 volumio volumio[6076]: [Metrics] CommandRouter: 24s 292.81ms Nov 18 00:43:19 volumio volumio[6076]: info: CoreCommandRouter::volumiosetStartupVolume Nov 18 00:43:19 volumio volumio[6076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:19 volumio volumio[6076]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:43:19 volumio volumio[6076]: info: CoreCommandRouter::Close All Modals sent Nov 18 00:43:19 volumio volumio[6076]: info: CoreCommandRouter::Close All Modals sent Nov 18 00:43:19 volumio volumio[6076]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Nov 18 00:43:19 volumio volumio[6076]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for x20 Nov 18 00:43:19 volumio volumio[6076]: aplay: main:831: audio open error: No such device Nov 18 00:43:20 volumio volumio[6076]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 18 00:43:20 volumio volumio[6076]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 18 00:43:20 volumio volumio[6076]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 18 00:43:23 volumio volumio[6076]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Nov 18 00:43:23 volumio volumio[6076]: info: Bluetoothremote--- Device list cleared and placeholder written. Nov 18 00:43:23 volumio bluetoothd[989]: Adv Monitor app :1.92 disconnected from D-Bus Nov 18 00:43:23 volumio volumio[6076]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 18 00:43:23 volumio volumio[6076]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Nov 18 00:43:23 volumio bluetoothd[989]: Adv Monitor app :1.93 disconnected from D-Bus Nov 18 00:43:24 volumio go-librespot[6374]: time="2025-11-18T00:43:24+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Nov 18 00:43:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 18 00:43:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 18 00:43:24 volumio volumio[6076]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 18 00:43:24 volumio volumio[6076]: Error: socket hang up Nov 18 00:43:24 volumio volumio[6076]: at connResetException (node:internal/errors:720:14) Nov 18 00:43:24 volumio volumio[6076]: at Socket.socketOnEnd (node:_http_client:519:23) Nov 18 00:43:24 volumio volumio[6076]: at Socket.emit (node:events:526:35) Nov 18 00:43:24 volumio volumio[6076]: at endReadableNT (node:internal/streams/readable:1376:12) Nov 18 00:43:24 volumio volumio[6076]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Nov 18 00:43:24 volumio volumio[6076]: code: 'ECONNRESET', Nov 18 00:43:24 volumio volumio[6076]: response: undefined Nov 18 00:43:24 volumio volumio[6076]: } Nov 18 00:43:24 volumio volumio[6076]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 18 00:43:25 volumio sudo[6486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-18 00:42' Nov 18 00:43:25 volumio sudo[6486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:25 volumio sudo[6486]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Nov 18 00:43:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 18 00:43:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 18 00:43:27 volumio go-librespot[6508]: go-librespot daemon starting... Nov 18 00:43:27 volumio go-librespot[6509]: time="2025-11-18T00:43:27+08:00" level=info msg="running go-librespot 0.4.0" Nov 18 00:43:27 volumio go-librespot[6509]: time="2025-11-18T00:43:27+08:00" level=debug msg="app state loaded" Nov 18 00:43:27 volumio go-librespot[6509]: time="2025-11-18T00:43:27+08:00" level=debug msg="stored credentials not found" Nov 18 00:43:27 volumio go-librespot[6509]: time="2025-11-18T00:43:27+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 18 00:43:53 volumio volumio-remote-updater[1007]: [2025-11-18 00:43:53] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Nov 18 00:43:53 volumio volumio-remote-updater[1007]: [2025-11-18 00:43:53] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Nov 18 00:43:53 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 18 00:43:53 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 18 00:43:53 volumio systemd[1]: volumio.service: Consumed 8.651s CPU time. Nov 18 00:43:53 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 18 00:43:53 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Nov 18 00:43:53 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Nov 18 00:43:53 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 18 00:43:53 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Nov 18 00:43:53 volumio systemd[1]: volumio.service: Consumed 8.651s CPU time. Nov 18 00:43:53 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Nov 18 00:43:53 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Nov 18 00:43:54 volumio volumio[6559]: info: ------------------------------------------- Nov 18 00:43:54 volumio volumio[6559]: info: ----- Volumio3 ---- Nov 18 00:43:54 volumio volumio[6559]: info: ------------------------------------------- Nov 18 00:43:54 volumio volumio[6559]: info: ----- System startup ---- Nov 18 00:43:54 volumio volumio[6559]: info: ------------------------------------------- Nov 18 00:43:54 volumio volumio[6559]: info: MYVOLUMIO Environment detected Nov 18 00:43:54 volumio volumio[6559]: info: Plugin folders cleanup Nov 18 00:43:54 volumio volumio[6559]: info: Scanning into folder /volumio/app/plugins/ Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category audio_interface Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category miscellanea Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category music_service Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category plugins.json Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category system_controller Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category user_interface Nov 18 00:43:54 volumio volumio[6559]: info: Scanning into folder /data/plugins/ Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category music_service Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category system_controller Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category system_hardware Nov 18 00:43:54 volumio volumio[6559]: info: Scanning category user_interface Nov 18 00:43:54 volumio volumio[6559]: info: Plugin folders cleanup completed Nov 18 00:43:54 volumio volumio[6559]: info: ------------------------------------------- Nov 18 00:43:54 volumio volumio[6559]: info: ----- Core plugins startup ---- Nov 18 00:43:54 volumio volumio[6559]: info: ------------------------------------------- Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugins from folder /volumio/app/plugins/ Nov 18 00:43:54 volumio volumio[6559]: info: Adding plugin upnp to MyMusic Plugins Nov 18 00:43:54 volumio volumio[6559]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 18 00:43:54 volumio volumio[6559]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugins from folder /data/plugins/ Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "system"... Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "appearance"... Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "network"... Nov 18 00:43:54 volumio volumio[6559]: info: Refreshing Cached IP Addresses Nov 18 00:43:54 volumio sudo[6587]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 18 00:43:54 volumio sudo[6587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:54 volumio sudo[6589]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 18 00:43:54 volumio sudo[6589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "services"... Nov 18 00:43:54 volumio sudo[6587]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "alsa_controller"... Nov 18 00:43:54 volumio sudo[6589]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:54 volumio sudo[6602]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 18 00:43:54 volumio sudo[6602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:54 volumio volumio[6559]: warn: Unable to locate the audio output device xCORE USB Audio 2.0. Please configure a valid output device. Nov 18 00:43:54 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "wizard"... Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "networkfs"... Nov 18 00:43:54 volumio volumio[6559]: info: Starting Udev Watcher for removable devices Nov 18 00:43:54 volumio volumio[6559]: info: Ignoring mount for partition: boot Nov 18 00:43:54 volumio volumio[6559]: info: Ignoring mount for partition: volumio Nov 18 00:43:54 volumio volumio[6559]: info: Ignoring mount for partition: volumio_data Nov 18 00:43:54 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "volumio_command_line_client"... Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "upnp"... Nov 18 00:43:54 volumio volumio[6559]: info: [1763397834677] Starting Upmpd Daemon Nov 18 00:43:54 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "my_music"... Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "mpd"... Nov 18 00:43:54 volumio volumio[6559]: info: Loading plugin "upnp_browser"... Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "alarm-clock"... Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "airplay_emulation"... Nov 18 00:43:55 volumio volumio[6559]: info: Starting Shairport Sync Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "last_100"... Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "webradio"... Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "i2s_dacs"... Nov 18 00:43:55 volumio volumio[6559]: info: I2S DAC not set, start Auto-detection Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "volumiodiscovery"... Nov 18 00:43:55 volumio volumio[6559]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 18 00:43:55 volumio volumio[6559]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 18 00:43:55 volumio volumio[6559]: *** WARNING *** For more information see Nov 18 00:43:55 volumio volumio[6559]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 18 00:43:55 volumio volumio[6559]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 18 00:43:55 volumio volumio[6559]: *** WARNING *** For more information see Nov 18 00:43:55 volumio node[6559]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 18 00:43:55 volumio node[6559]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 18 00:43:55 volumio node[6559]: *** WARNING *** For more information see Nov 18 00:43:55 volumio node[6559]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 18 00:43:55 volumio node[6559]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 18 00:43:55 volumio node[6559]: *** WARNING *** For more information see Nov 18 00:43:55 volumio volumio[6559]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 18 00:43:55 volumio volumio[6559]: info: Discovery: Started advertising with name: Volumio Nov 18 00:43:55 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "spop"... Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "ytmusic"... Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "autostart"... Nov 18 00:43:55 volumio volumio[6559]: info: Applying required configuration parameters for plugin autostart Nov 18 00:43:55 volumio volumio[6559]: info: AutoStart - onVolumioStart - read config.json Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "outputs"... Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "albumart"... Nov 18 00:43:55 volumio volumio[6559]: info: Plugin example_plugin is not enabled Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "inputs"... Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "updater_comm"... Nov 18 00:43:55 volumio volumio[6559]: info: Plugin mpdemulation is not enabled Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "rest_api"... Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "websocket"... Nov 18 00:43:55 volumio volumio[6559]: info: Starting Socket.io Server version 1.7.4 Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "RoonBridge"... Nov 18 00:43:55 volumio volumio[6559]: info: Applying required configuration parameters for plugin RoonBridge Nov 18 00:43:55 volumio volumio[6559]: info: Loading plugin "myb"... Nov 18 00:43:56 volumio volumio[6622]: Forking 3 albumart workers Nov 18 00:43:56 volumio go-librespot[6509]: time="2025-11-18T00:43:56+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Nov 18 00:43:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 18 00:43:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 18 00:43:56 volumio volumio[6559]: info: Applying required configuration parameters for plugin myb Nov 18 00:43:56 volumio volumio[6559]: info: Loading plugin "Bluetoothremote"... Nov 18 00:43:56 volumio sudo[6602]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:56 volumio volumio[6559]: info: Applying required configuration parameters for plugin Bluetoothremote Nov 18 00:43:56 volumio volumio[6559]: info: Loading plugin "ir_controller"... Nov 18 00:43:56 volumio volumio[6559]: info: Applying required configuration parameters for plugin ir_controller Nov 18 00:43:56 volumio volumio[6559]: info: Loading plugin "Systeminfo"... Nov 18 00:43:56 volumio volumio[6559]: info: Loading plugin "touch_display"... Nov 18 00:43:56 volumio volumio[6559]: info: Applying required configuration parameters for plugin touch_display Nov 18 00:43:56 volumio volumio[6559]: info: Loading i18n strings for locale zh Nov 18 00:43:56 volumio volumio[6559]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:43:56 volumio volumio[6559]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:43:56 volumio volumio[6559]: Updating browse sources language Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:43:56 volumio volumio[6633]: Starting albumart workers Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::initPlayerControls Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6634]: Starting albumart workers Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: Express server listening on port 3000 Nov 18 00:43:56 volumio volumio[6559]: [Metrics] WebUI: 2s 536.15ms Nov 18 00:43:56 volumio volumio[6632]: Starting albumart workers Nov 18 00:43:56 volumio volumio[6559]: info: CoreStateMachine::resetVolumioState Nov 18 00:43:56 volumio volumio[6559]: info: CoreStateMachine::getcurrentVolume Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioRetrievevolume Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: Volumio Network Manager: Network status updated: 2 Nov 18 00:43:56 volumio volumio[6559]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 18 00:43:56 volumio volumio[6559]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'xCORE USB Audio 2.0 Output',0 Nov 18 00:43:56 volumio volumio[6559]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 18 00:43:56 volumio volumio[6559]: info: Reloading queue from file Nov 18 00:43:56 volumio volumio[6559]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 18 00:43:56 volumio volumio[6559]: info: VolumeController:: Volume=undefined Mute =false Nov 18 00:43:56 volumio volumio[6559]: info: CoreStateMachine::pushState Nov 18 00:43:56 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioPushState Nov 18 00:43:56 volumio volumio[6559]: info: CoreStateMachine::updateTrackBlock Nov 18 00:43:56 volumio volumio[6559]: info: CorePlayQueue::getTrackBlock Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioRetrievevolume Nov 18 00:43:56 volumio volumio[6559]: info: CoreStateMachine::setRepeat null single undefined Nov 18 00:43:56 volumio volumio[6559]: info: CoreStateMachine::pushState Nov 18 00:43:56 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioPushState Nov 18 00:43:56 volumio volumio[6559]: info: CoreStateMachine::setRandom null Nov 18 00:43:56 volumio volumio[6559]: info: CoreStateMachine::pushState Nov 18 00:43:56 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioPushState Nov 18 00:43:56 volumio volumio[6559]: info: Setting Device type: Raspberry PI Nov 18 00:43:56 volumio volumio[6559]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 18 00:43:56 volumio volumio[6559]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Nov 18 00:43:56 volumio volumio[6559]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 18 00:43:56 volumio volumio[6559]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'xCORE USB Audio 2.0 Output',0 Nov 18 00:43:56 volumio volumio[6559]: info: VolumeController:: Volume=undefined Mute =false Nov 18 00:43:56 volumio volumio[6559]: info: CoreStateMachine::pushState Nov 18 00:43:56 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioPushState Nov 18 00:43:56 volumio volumio[6559]: info: Completed loading Core Plugins Nov 18 00:43:56 volumio volumio[6559]: info: Preparing to generate the ALSA configuration file Nov 18 00:43:56 volumio volumio[6559]: info: Asound.conf file unchanged, so no further update is needed Nov 18 00:43:56 volumio volumio[6559]: info: Output device has changed, restarting MPD Nov 18 00:43:56 volumio volumio[6559]: info: Output device has changed, restarting Shairport Sync Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:43:56 volumio sudo[6698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 18 00:43:56 volumio sudo[6698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:56 volumio sudo[6698]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:56 volumio sudo[6700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 18 00:43:56 volumio sudo[6700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:56 volumio volumio[6559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 18 00:43:56 volumio volumio[6559]: info: ___________ START PLUGINS ___________ Nov 18 00:43:56 volumio volumio[6559]: info: ControllerMpd::onStart: Initializing MPD Nov 18 00:43:56 volumio volumio[6559]: info: Creating MPD Configuration file Nov 18 00:43:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 18 00:43:56 volumio sudo[6708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 18 00:43:56 volumio sudo[6708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 18 00:43:56 volumio volumio[6559]: info: [1763397836828] CoreMusicLibrary::Adding element 媒体服务器 Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:43:56 volumio sudo[6708]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:56 volumio sudo[6710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:43:56 volumio sudo[6710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:56 volumio volumio[6559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:43:56 volumio systemd[1]: mpd.service: Deactivated successfully. Nov 18 00:43:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 18 00:43:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Nov 18 00:43:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 18 00:43:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 18 00:43:56 volumio volumio[6559]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 18 00:43:56 volumio volumio[6559]: info: [1763397836860] CoreMusicLibrary::Adding element Last_100 Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 18 00:43:56 volumio volumio[6559]: info: [1763397836861] CoreMusicLibrary::Adding element Webradio Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:43:56 volumio volumio[6559]: info: Initializing BBC Radios Nov 18 00:43:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 18 00:43:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Nov 18 00:43:56 volumio systemd[1]: mpd.service: Deactivated successfully. Nov 18 00:43:56 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 18 00:43:56 volumio systemd[1]: mpd.socket: Deactivated successfully. Nov 18 00:43:56 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 18 00:43:56 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 18 00:43:56 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:43:56 volumio volumio[6559]: info: Creating Spotify config file Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:56 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 18 00:43:56 volumio volumio[6559]: info: [1763397836902] CoreMusicLibrary::Adding element YouTube Music Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:43:56 volumio volumio[6559]: Cannot find translation for source YouTube Music Nov 18 00:43:56 volumio volumio[6559]: info: AutoStart - onStart Nov 18 00:43:56 volumio volumio[6559]: info: Loading i18n strings for locale zh Nov 18 00:43:56 volumio volumio[6559]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:43:56 volumio volumio[6559]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:43:56 volumio volumio[6559]: info: Loading i18n strings for locale zh Nov 18 00:43:56 volumio sudo[6740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 18 00:43:56 volumio sudo[6740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:56 volumio volumio[6559]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:43:56 volumio volumio[6559]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:43:56 volumio volumio[6559]: info: Loading i18n strings for locale zh Nov 18 00:43:56 volumio volumio[6559]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:43:56 volumio volumio[6559]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:43:56 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:43:56 volumio volumio[6559]: info: Volumio Calling Home Nov 18 00:43:56 volumio sudo[6740]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:56 volumio sudo[6744]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Nov 18 00:43:56 volumio sudo[6744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:56 volumio sudo[6744]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:56 volumio sudo[6738]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 18 00:43:56 volumio sudo[6738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 18 00:43:56 volumio sudo[6738]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:56 volumio sudo[6755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Nov 18 00:43:56 volumio sudo[6755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:56 volumio sudo[6755]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:56 volumio sudo[6757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 18 00:43:56 volumio sudo[6757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:56 volumio sudo[6760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 18 00:43:56 volumio sudo[6760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:57 volumio sudo[6762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 18 00:43:57 volumio sudo[6762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:57 volumio sudo[6757]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:57 volumio systemd[1]: Reloading. Nov 18 00:43:57 volumio volumio[6559]: info: touch_display: No backlight interface detected. Nov 18 00:43:57 volumio volumio[6559]: info: Discovery: adding 6d0ee261-5909-4bd4-ad3b-e79afe64531c Nov 18 00:43:57 volumio volumio[6559]: info: Discovery: Found device Volumio Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:43:57 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:57 volumio volumio[6559]: info: Discovery: this is already registered, 6d0ee261-5909-4bd4-ad3b-e79afe64531c Nov 18 00:43:57 volumio volumio[6559]: info: Discovery: Found device Volumio Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:43:57 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:57 volumio volumio[6559]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 18 00:43:57 volumio volumio[6559]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Nov 18 00:43:57 volumio volumio[6559]: info: MPD Permissions set Nov 18 00:43:57 volumio volumio[6559]: info: MPD Permissions set Nov 18 00:43:57 volumio volumio[6559]: info: Spotify config file written Nov 18 00:43:57 volumio sudo[6768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Nov 18 00:43:57 volumio sudo[6768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:57 volumio sudo[6768]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:57 volumio volumio[6559]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 18 00:43:57 volumio sudo[6770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 18 00:43:57 volumio sudo[6770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:43:57 volumio volumio[6559]: info: No need to fix Spotify hosts Nov 18 00:43:57 volumio volumio[6559]: info: touch_display: IframeKeyboardBridge extension installed successfully Nov 18 00:43:57 volumio volumio[6559]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 18 00:43:57 volumio volumio[6559]: info: Starting Shairport Sync Nov 18 00:43:57 volumio volumio[6559]: info: Starting Shairport Sync Nov 18 00:43:57 volumio sudo[6801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 18 00:43:57 volumio sudo[6801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:57 volumio sudo[6803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 18 00:43:57 volumio sudo[6801]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:57 volumio sudo[6806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 18 00:43:57 volumio sudo[6803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:57 volumio sudo[6806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:57 volumio sudo[6803]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:57 volumio volumio[6559]: info: Starting Shairport Sync Nov 18 00:43:57 volumio sudo[6810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 18 00:43:57 volumio volumio[6559]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 18 00:43:57 volumio volumio[6559]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 18 00:43:57 volumio sudo[6812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 18 00:43:57 volumio sudo[6810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:57 volumio sudo[6812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:57 volumio volumio[6559]: info: ir_controller: LIRC correctly updated. Nov 18 00:43:57 volumio volumio[6559]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Nov 18 00:43:57 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:43:57 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:57 volumio systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:43:57 volumio systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:43:57 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:43:57 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:43:57 volumio mpd[6753]: 2025-11-18T00:43:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 18 00:43:57 volumio systemd[1]: Started mpd.service - Music Player Daemon. Nov 18 00:43:57 volumio sudo[6760]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:57 volumio systemd[1]: Reloading. Nov 18 00:43:57 volumio volumio[6559]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 18 00:43:57 volumio sudo[6700]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:57 volumio sudo[6710]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:57 volumio volumio[6559]: error: MPD error: The expression evaluated to a falsy value: Nov 18 00:43:57 volumio volumio[6559]: assert.ok(self.idling) Nov 18 00:43:57 volumio volumio[6559]: error: The expression evaluated to a falsy value: Nov 18 00:43:57 volumio volumio[6559]: assert.ok(self.idling) Nov 18 00:43:57 volumio volumio[6559]: error: updateQueue error: null Nov 18 00:43:57 volumio volumio[6559]: info: MPD running with PID6753 Nov 18 00:43:57 volumio volumio[6559]: ,establishing connection Nov 18 00:43:57 volumio volumio[6559]: error: updateQueue error: null Nov 18 00:43:58 volumio systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:43:58 volumio systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:43:58 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:43:58 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:43:58 volumio sudo[6762]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:58 volumio volumio[6559]: info: touch_display: systemctl daemon-reload succeeded. Nov 18 00:43:58 volumio sudo[6837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 18 00:43:58 volumio sudo[6837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:58 volumio volumio-remote-updater[1007]: [2025-11-18 00:43:58] [connect] Successful connection Nov 18 00:43:58 volumio volumio-remote-updater[1007]: [2025-11-18 00:43:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1763397838 101 Nov 18 00:43:58 volumio volumio[6559]: 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: 5 Nov 18 00:43:58 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 18 00:43:58 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 18 00:43:58 volumio go-librespot[6839]: go-librespot daemon starting... Nov 18 00:43:58 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 18 00:43:58 volumio sudo[6770]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:58 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Nov 18 00:43:58 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 18 00:43:58 volumio systemd[1]: shairport-sync.service: Consumed 1.585s CPU time. Nov 18 00:43:58 volumio sudo[6837]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:58 volumio volumio[6559]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 18 00:43:58 volumio volumio[6559]: info: touch_display: Volumio Kiosk started. Nov 18 00:43:58 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 18 00:43:58 volumio sudo[6812]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:58 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:43:58 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:58 volumio volumio[6559]: info: Completed starting Core Plugins Nov 18 00:43:58 volumio volumio[6559]: info: ------------------------------------------- Nov 18 00:43:58 volumio volumio[6559]: info: ----- MyVolumio plugins startup ---- Nov 18 00:43:58 volumio volumio[6559]: info: ------------------------------------------- Nov 18 00:43:58 volumio volumio[6559]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 18 00:43:58 volumio go-librespot[6840]: time="2025-11-18T00:43:58+08:00" level=info msg="running go-librespot 0.4.0" Nov 18 00:43:58 volumio go-librespot[6840]: time="2025-11-18T00:43:58+08:00" level=debug msg="app state loaded" Nov 18 00:43:58 volumio go-librespot[6840]: time="2025-11-18T00:43:58+08:00" level=debug msg="stored credentials not found" Nov 18 00:43:58 volumio sudo[6810]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:58 volumio go-librespot[6840]: time="2025-11-18T00:43:58+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 18 00:43:58 volumio sudo[6806]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:58 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:43:58 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:43:58 volumio volumio[6559]: info: Shairport-Sync Started Nov 18 00:43:58 volumio volumio[6559]: Error adding Membership: Error: addMembership EINVAL Nov 18 00:43:58 volumio volumio[6559]: info: Shairport-Sync Started Nov 18 00:43:58 volumio volumio[6559]: info: Shairport-Sync Started Nov 18 00:43:58 volumio sudo[6851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Nov 18 00:43:58 volumio sudo[6851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:58 volumio volumio[6559]: info: touch_display: X display number found: 0 Nov 18 00:43:58 volumio lircd[6399]: lircd-0.10.1[6399]: Notice: caught signal Nov 18 00:43:58 volumio systemd[1]: Stopping lircd.service - Flexible IR remote input/output application support... Nov 18 00:43:58 volumio lircd-0.10.1[6399]: Notice: caught signal Nov 18 00:43:58 volumio systemd[1]: lircd.service: Deactivated successfully. Nov 18 00:43:58 volumio systemd[1]: Stopped lircd.service - Flexible IR remote input/output application support. Nov 18 00:43:58 volumio systemd[1]: irexec.service: Deactivated successfully. Nov 18 00:43:58 volumio systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Nov 18 00:43:58 volumio volumio[6559]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 18 00:43:58 volumio lircd-setup[6867]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. Nov 18 00:43:58 volumio lircd-setup[6867]: parser = configparser.SafeConfigParser() Nov 18 00:43:58 volumio volumio[6559]: info: touch_display: X display number found: 0 Nov 18 00:43:58 volumio volumio[6559]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 18 00:43:58 volumio systemd[1]: lircd-setup.service: Deactivated successfully. Nov 18 00:43:58 volumio systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Nov 18 00:43:58 volumio systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Info: lircd: Opening log, level: Info Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Version: lircd 0.10.1 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: System info: Linux volumio 6.12.47-v8+ #1904 SMP PREEMPT Mon Sep 15 13:10:52 BST 2025 aarch64 GNU/Linux Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Info: Initial device: /dev/lirc0 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Info: [lirc] protocol is enabled Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Info: Initial device: /dev/lirc0 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Info: [lirc] protocol is enabled Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: driver: default Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: output: /var/run/lirc/lircd Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: nodaemon: 1 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: logfile: syslog Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: immediate-init: 0 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: permission: 666 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: driver-options: Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: listen: 0 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: connect: (null) Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: userelease: 0 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: effective_user: (null) Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: release_suffix: _EVUP Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: allow_simulate: 0 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: repeat_max: 600 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Options: dynamic_codes: (null) Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Current driver: default Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Driver API version: 3 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Driver version: 0.10.0 Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 18 00:43:58 volumio lircd[6874]: lircd-0.10.1[6874]: Info: lircd: Opening log, level: Info Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Info: lircd: Opening log, level: Info Nov 18 00:43:58 volumio lircd[6874]: lircd-0.10.1[6874]: Notice: Using systemd fd Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: Using systemd fd Nov 18 00:43:58 volumio lircd[6874]: lircd-0.10.1[6874]: Warning: Running as root Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Warning: Running as root Nov 18 00:43:58 volumio lircd[6874]: lircd-0.10.1[6874]: Info: Using remote: lircd.conf. Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Info: Using remote: lircd.conf. Nov 18 00:43:58 volumio lircd[6874]: lircd-0.10.1[6874]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 18 00:43:58 volumio systemd[1]: Started lircd.service - Flexible IR remote input/output application support. Nov 18 00:43:58 volumio sudo[6851]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:58 volumio volumio[6559]: info: ir_controller: systemctl restart lircd.service succeeded. Nov 18 00:43:58 volumio sudo[6878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Nov 18 00:43:58 volumio sudo[6878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:43:58 volumio systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). Nov 18 00:43:58 volumio sudo[6878]: pam_unix(sudo:session): session closed for user root Nov 18 00:43:58 volumio lircd[6874]: lircd-0.10.1[6874]: Notice: accepted new client on /var/run/lirc/lircd Nov 18 00:43:58 volumio lircd[6874]: lircd-0.10.1[6874]: Info: [lirc] protocol is enabled Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Notice: accepted new client on /var/run/lirc/lircd Nov 18 00:43:58 volumio lircd-0.10.1[6874]: Info: [lirc] protocol is enabled Nov 18 00:43:58 volumio volumio[6559]: info: ir_controller: systemctl restart irexec.service succeeded. Nov 18 00:44:00 volumio sudo[6883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Nov 18 00:44:00 volumio sudo[6883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:00 volumio sudo[6885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Nov 18 00:44:00 volumio sudo[6885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:00 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Nov 18 00:44:00 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Nov 18 00:44:00 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Nov 18 00:44:00 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Nov 18 00:44:00 volumio sudo[6883]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:00 volumio mpd_monitor.sh[6888]: MPD Monitor Service: Starting MPD Monitor Service Nov 18 00:44:00 volumio sudo[6885]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:00 volumio volumio[6559]: info: Successfully started MPD Monitor Nov 18 00:44:00 volumio volumio[6559]: info: Successfully started MPD Monitor Nov 18 00:44:01 volumio volumio[6559]: info: go-librespot daemon successfully initialized Nov 18 00:44:01 volumio volumio[6559]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::volumioGetVisibleSources Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:01 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:01 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 18 00:44:01 volumio volumio[6559]: info: Received Get System Info Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 18 00:44:01 volumio volumio[6559]: info: Discovery: Getting this device information Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:01 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 18 00:44:01 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:01 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:01 volumio volumio[6559]: info: Listing playlists Nov 18 00:44:02 volumio volumio[6559]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 18 00:44:04 volumio volumio[6559]: info: Initializing connection to go-librespot Websocket Nov 18 00:44:04 volumio go-librespot[6840]: time="2025-11-18T00:44:04+08:00" level=debug msg="new websocket client" Nov 18 00:44:04 volumio volumio[6559]: info: Connection to go-librespot Websocket established Nov 18 00:44:04 volumio sudo[6908]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 18 00:44:04 volumio sudo[6908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:04 volumio sudo[6910]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 18 00:44:04 volumio sudo[6910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:04 volumio sudo[6908]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:04 volumio sudo[6910]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:04 volumio sudo[6912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 18 00:44:04 volumio sudo[6912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:04 volumio sudo[6912]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:04 volumio volumio[6559]: info: Upmpdcli Daemon Started Nov 18 00:44:07 volumio volumio[6559]: info: Getting Spotify volume Nov 18 00:44:07 volumio volumio[6559]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 18 00:44:07 volumio volumio[6559]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:07 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:16 volumio volumio[6559]: info: AutoStart - Plugin is starting Nov 18 00:44:16 volumio volumio[6559]: info: CoreCommandRouter::volumioGetQueue Nov 18 00:44:16 volumio volumio[6559]: info: CoreStateMachine::getQueue Nov 18 00:44:16 volumio volumio[6559]: info: CorePlayQueue::getQueue Nov 18 00:44:16 volumio volumio[6559]: info: AutoStart - start playing Nov 18 00:44:16 volumio volumio[6559]: info: AutoStart - start playing with no specific position Nov 18 00:44:16 volumio volumio[6559]: info: CoreCommandRouter::volumioPlay Nov 18 00:44:16 volumio volumio[6559]: info: CoreStateMachine::play index 0 Nov 18 00:44:16 volumio volumio[6559]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 18 00:44:16 volumio volumio[6559]: info: CoreStateMachine::stop Nov 18 00:44:16 volumio volumio[6559]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 18 00:44:16 volumio volumio[6559]: info: CoreStateMachine::play index undefined Nov 18 00:44:16 volumio volumio[6559]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 18 00:44:16 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:16 volumio volumio[6559]: info: CoreStateMachine::startPlaybackTimer Nov 18 00:44:16 volumio volumio[6559]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:16 volumio volumio[6559]: verbose: ControllerMpd::clearAddPlayTracks USB/CB78-A5DB/徐小明-撼江湖2 HQII/01.男儿当自强.wav Nov 18 00:44:16 volumio volumio[6559]: verbose: ControllerMpd::sendMpdCommand stop Nov 18 00:44:16 volumio volumio[6559]: info: sendMpdCommand stop took 0 milliseconds Nov 18 00:44:16 volumio volumio[6559]: verbose: ControllerMpd::sendMpdCommand clear Nov 18 00:44:16 volumio volumio[6559]: info: Nov 18 00:44:16 volumio volumio[6559]: ---------------------------- MPD announces system playlist update Nov 18 00:44:16 volumio volumio[6559]: info: Ignoring MPD Status Update Nov 18 00:44:16 volumio volumio[6559]: info: sendMpdCommand clear took 1 milliseconds Nov 18 00:44:16 volumio volumio[6559]: verbose: ControllerMpd::sendMpdCommand add "USB/CB78-A5DB/徐小明-撼江湖2 HQII/01.男儿当自强.wav" Nov 18 00:44:16 volumio volumio[6559]: error: updateQueue error: null Nov 18 00:44:16 volumio volumio[6559]: info: ------------------------------ 1ms Nov 18 00:44:16 volumio volumio[6559]: info: Nov 18 00:44:16 volumio volumio[6559]: ---------------------------- MPD announces system playlist update Nov 18 00:44:16 volumio volumio[6559]: info: Ignoring MPD Status Update Nov 18 00:44:16 volumio volumio[6559]: info: Nov 18 00:44:16 volumio volumio[6559]: ---------------------------- MPD announces system playlist update Nov 18 00:44:16 volumio volumio[6559]: info: Ignoring MPD Status Update Nov 18 00:44:16 volumio volumio[6559]: error: updateQueue error: null Nov 18 00:44:16 volumio volumio[6559]: error: updateQueue error: null Nov 18 00:44:16 volumio volumio[6559]: info: ------------------------------ 1ms Nov 18 00:44:16 volumio volumio[6559]: info: ------------------------------ 1ms Nov 18 00:44:17 volumio go-librespot[6840]: time="2025-11-18T00:44:17+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Nov 18 00:44:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 18 00:44:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 18 00:44:17 volumio volumio[6559]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 18 00:44:17 volumio volumio[6559]: Error: socket hang up Nov 18 00:44:17 volumio volumio[6559]: at connResetException (node:internal/errors:720:14) Nov 18 00:44:17 volumio volumio[6559]: at Socket.socketOnEnd (node:_http_client:519:23) Nov 18 00:44:17 volumio volumio[6559]: at Socket.emit (node:events:526:35) Nov 18 00:44:17 volumio volumio[6559]: at endReadableNT (node:internal/streams/readable:1376:12) Nov 18 00:44:17 volumio volumio[6559]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Nov 18 00:44:17 volumio volumio[6559]: code: 'ECONNRESET', Nov 18 00:44:17 volumio volumio[6559]: response: undefined Nov 18 00:44:17 volumio volumio[6559]: } Nov 18 00:44:17 volumio volumio[6559]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 18 00:44:17 volumio sudo[6944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-18 00:43' Nov 18 00:44:17 volumio sudo[6944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:17 volumio sudo[6944]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:17 volumio volumio-remote-updater[1007]: [2025-11-18 00:44:17] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 18 00:44:17 volumio volumio-remote-updater[1007]: [2025-11-18 00:44:17] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 18 00:44:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 18 00:44:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 18 00:44:17 volumio systemd[1]: volumio.service: Consumed 8.476s CPU time. Nov 18 00:44:17 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 18 00:44:17 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Nov 18 00:44:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Nov 18 00:44:17 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Nov 18 00:44:17 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Nov 18 00:44:17 volumio systemd[1]: volumio.service: Consumed 8.476s CPU time. Nov 18 00:44:17 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Nov 18 00:44:18 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Nov 18 00:44:18 volumio volumio[6959]: info: ------------------------------------------- Nov 18 00:44:18 volumio volumio[6959]: info: ----- Volumio3 ---- Nov 18 00:44:18 volumio volumio[6959]: info: ------------------------------------------- Nov 18 00:44:18 volumio volumio[6959]: info: ----- System startup ---- Nov 18 00:44:18 volumio volumio[6959]: info: ------------------------------------------- Nov 18 00:44:18 volumio volumio[6959]: info: MYVOLUMIO Environment detected Nov 18 00:44:18 volumio volumio[6959]: info: Plugin folders cleanup Nov 18 00:44:18 volumio volumio[6959]: info: Scanning into folder /volumio/app/plugins/ Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category audio_interface Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category miscellanea Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category music_service Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category plugins.json Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category system_controller Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category user_interface Nov 18 00:44:18 volumio volumio[6959]: info: Scanning into folder /data/plugins/ Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category music_service Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category system_controller Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category system_hardware Nov 18 00:44:18 volumio volumio[6959]: info: Scanning category user_interface Nov 18 00:44:18 volumio volumio[6959]: info: Plugin folders cleanup completed Nov 18 00:44:18 volumio volumio[6959]: info: ------------------------------------------- Nov 18 00:44:18 volumio volumio[6959]: info: ----- Core plugins startup ---- Nov 18 00:44:18 volumio volumio[6959]: info: ------------------------------------------- Nov 18 00:44:18 volumio volumio[6959]: info: Loading plugins from folder /volumio/app/plugins/ Nov 18 00:44:18 volumio volumio[6959]: info: Adding plugin upnp to MyMusic Plugins Nov 18 00:44:18 volumio volumio[6959]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 18 00:44:18 volumio volumio[6959]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 18 00:44:18 volumio volumio[6959]: info: Loading plugins from folder /data/plugins/ Nov 18 00:44:18 volumio volumio[6959]: info: Loading plugin "system"... Nov 18 00:44:18 volumio volumio[6959]: info: Loading plugin "appearance"... Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "network"... Nov 18 00:44:19 volumio volumio[6959]: info: Refreshing Cached IP Addresses Nov 18 00:44:19 volumio sudo[6987]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 18 00:44:19 volumio sudo[6987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "services"... Nov 18 00:44:19 volumio sudo[6987]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:19 volumio sudo[6989]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "alsa_controller"... Nov 18 00:44:19 volumio sudo[6989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:19 volumio sudo[6989]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:19 volumio sudo[6994]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 18 00:44:19 volumio sudo[6994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:19 volumio volumio[6959]: warn: Unable to locate the audio output device xCORE USB Audio 2.0. Please configure a valid output device. Nov 18 00:44:19 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "wizard"... Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "networkfs"... Nov 18 00:44:19 volumio volumio[6959]: info: Starting Udev Watcher for removable devices Nov 18 00:44:19 volumio volumio[6959]: info: Ignoring mount for partition: boot Nov 18 00:44:19 volumio volumio[6959]: info: Ignoring mount for partition: volumio Nov 18 00:44:19 volumio volumio[6959]: info: Ignoring mount for partition: volumio_data Nov 18 00:44:19 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "volumio_command_line_client"... Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "upnp"... Nov 18 00:44:19 volumio volumio[6959]: info: [1763397859176] Starting Upmpd Daemon Nov 18 00:44:19 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "my_music"... Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "mpd"... Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "upnp_browser"... Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "alarm-clock"... Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "airplay_emulation"... Nov 18 00:44:19 volumio volumio[6959]: info: Starting Shairport Sync Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "last_100"... Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "webradio"... Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "i2s_dacs"... Nov 18 00:44:19 volumio volumio[6959]: info: I2S DAC not set, start Auto-detection Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "volumiodiscovery"... Nov 18 00:44:19 volumio volumio[6959]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 18 00:44:19 volumio volumio[6959]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 18 00:44:19 volumio volumio[6959]: *** WARNING *** For more information see Nov 18 00:44:19 volumio volumio[6959]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 18 00:44:19 volumio volumio[6959]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 18 00:44:19 volumio volumio[6959]: *** WARNING *** For more information see Nov 18 00:44:19 volumio node[6959]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 18 00:44:19 volumio node[6959]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 18 00:44:19 volumio node[6959]: *** WARNING *** For more information see Nov 18 00:44:19 volumio node[6959]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 18 00:44:19 volumio node[6959]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 18 00:44:19 volumio node[6959]: *** WARNING *** For more information see Nov 18 00:44:19 volumio volumio[6959]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 18 00:44:19 volumio volumio[6959]: info: Discovery: Started advertising with name: Volumio Nov 18 00:44:19 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 18 00:44:19 volumio volumio[6959]: info: Loading plugin "spop"... Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "ytmusic"... Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "autostart"... Nov 18 00:44:20 volumio volumio[6959]: info: Applying required configuration parameters for plugin autostart Nov 18 00:44:20 volumio volumio[6959]: info: AutoStart - onVolumioStart - read config.json Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "outputs"... Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "albumart"... Nov 18 00:44:20 volumio volumio[6959]: info: Plugin example_plugin is not enabled Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "inputs"... Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "updater_comm"... Nov 18 00:44:20 volumio volumio[6959]: info: Plugin mpdemulation is not enabled Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "rest_api"... Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "websocket"... Nov 18 00:44:20 volumio volumio[6959]: info: Starting Socket.io Server version 1.7.4 Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "RoonBridge"... Nov 18 00:44:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Nov 18 00:44:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 18 00:44:20 volumio volumio[6959]: info: Applying required configuration parameters for plugin RoonBridge Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "myb"... Nov 18 00:44:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 18 00:44:20 volumio go-librespot[7033]: go-librespot daemon starting... Nov 18 00:44:20 volumio go-librespot[7034]: time="2025-11-18T00:44:20+08:00" level=info msg="running go-librespot 0.4.0" Nov 18 00:44:20 volumio go-librespot[7034]: time="2025-11-18T00:44:20+08:00" level=debug msg="app state loaded" Nov 18 00:44:20 volumio go-librespot[7034]: time="2025-11-18T00:44:20+08:00" level=debug msg="stored credentials not found" Nov 18 00:44:20 volumio go-librespot[7034]: time="2025-11-18T00:44:20+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 18 00:44:20 volumio volumio[7023]: Forking 3 albumart workers Nov 18 00:44:20 volumio volumio[6959]: info: Applying required configuration parameters for plugin myb Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "Bluetoothremote"... Nov 18 00:44:20 volumio sudo[6994]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:20 volumio volumio[6959]: info: Applying required configuration parameters for plugin Bluetoothremote Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "ir_controller"... Nov 18 00:44:20 volumio volumio[6959]: info: Applying required configuration parameters for plugin ir_controller Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "Systeminfo"... Nov 18 00:44:20 volumio volumio[6959]: info: Loading plugin "touch_display"... Nov 18 00:44:20 volumio volumio[7040]: Starting albumart workers Nov 18 00:44:20 volumio volumio[6959]: info: Applying required configuration parameters for plugin touch_display Nov 18 00:44:20 volumio volumio[7042]: Starting albumart workers Nov 18 00:44:20 volumio volumio[7041]: Starting albumart workers Nov 18 00:44:20 volumio volumio[6959]: info: Loading i18n strings for locale zh Nov 18 00:44:20 volumio volumio[6959]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:44:20 volumio volumio[6959]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:44:20 volumio volumio[6959]: Updating browse sources language Nov 18 00:44:20 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::initPlayerControls Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: Express server listening on port 3000 Nov 18 00:44:21 volumio volumio[6959]: [Metrics] WebUI: 2s 605.82ms Nov 18 00:44:21 volumio volumio[6959]: info: CoreStateMachine::resetVolumioState Nov 18 00:44:21 volumio volumio[6959]: info: CoreStateMachine::getcurrentVolume Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioRetrievevolume Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: Volumio Network Manager: Network status updated: 2 Nov 18 00:44:21 volumio volumio[6959]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'xCORE USB Audio 2.0 Output',0 Nov 18 00:44:21 volumio volumio[6959]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 18 00:44:21 volumio volumio[6959]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 18 00:44:21 volumio volumio[6959]: info: VolumeController:: Volume=undefined Mute =false Nov 18 00:44:21 volumio volumio[6959]: info: CoreStateMachine::pushState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioPushState Nov 18 00:44:21 volumio volumio[6959]: info: CoreStateMachine::updateTrackBlock Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrackBlock Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioRetrievevolume Nov 18 00:44:21 volumio volumio[6959]: info: Reloading queue from file Nov 18 00:44:21 volumio volumio[6959]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 18 00:44:21 volumio volumio[6959]: info: CoreStateMachine::setRepeat null single undefined Nov 18 00:44:21 volumio volumio[6959]: info: CoreStateMachine::pushState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioPushState Nov 18 00:44:21 volumio volumio[6959]: info: CoreStateMachine::setRandom null Nov 18 00:44:21 volumio volumio[6959]: info: CoreStateMachine::pushState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioPushState Nov 18 00:44:21 volumio volumio[6959]: info: Setting Device type: Raspberry PI Nov 18 00:44:21 volumio volumio[6959]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 18 00:44:21 volumio volumio[6959]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Nov 18 00:44:21 volumio volumio[6959]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 18 00:44:21 volumio volumio[6959]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'xCORE USB Audio 2.0 Output',0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 18 00:44:21 volumio volumio[6959]: info: VolumeController:: Volume=undefined Mute =false Nov 18 00:44:21 volumio volumio[6959]: info: CoreStateMachine::pushState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioPushState Nov 18 00:44:21 volumio volumio[6959]: info: Completed loading Core Plugins Nov 18 00:44:21 volumio volumio[6959]: info: Preparing to generate the ALSA configuration file Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioGetVisibleSources Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 18 00:44:21 volumio volumio[6959]: info: Received Get System Info Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 18 00:44:21 volumio volumio[6959]: info: Discovery: Getting this device information Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: Listing playlists Nov 18 00:44:21 volumio volumio[6959]: info: Asound.conf file unchanged, so no further update is needed Nov 18 00:44:21 volumio volumio[6959]: info: Output device has changed, restarting MPD Nov 18 00:44:21 volumio volumio[6959]: info: Output device has changed, restarting Shairport Sync Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:44:21 volumio sudo[7110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 18 00:44:21 volumio sudo[7110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio sudo[7110]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:21 volumio volumio[6959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 18 00:44:21 volumio volumio[6959]: info: ___________ START PLUGINS ___________ Nov 18 00:44:21 volumio sudo[7113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 18 00:44:21 volumio sudo[7113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio volumio[6959]: info: ControllerMpd::onStart: Initializing MPD Nov 18 00:44:21 volumio volumio[6959]: info: Creating MPD Configuration file Nov 18 00:44:21 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 18 00:44:21 volumio volumio[6959]: info: [1763397861428] CoreMusicLibrary::Adding element 媒体服务器 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:44:21 volumio systemd[1]: mpd.service: Deactivated successfully. Nov 18 00:44:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 18 00:44:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Nov 18 00:44:21 volumio volumio[6959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 18 00:44:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:44:21 volumio sudo[7120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 18 00:44:21 volumio sudo[7120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio sudo[7122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 18 00:44:21 volumio sudo[7122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio sudo[7120]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:21 volumio volumio[6959]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 18 00:44:21 volumio volumio[6959]: info: [1763397861464] CoreMusicLibrary::Adding element Last_100 Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 18 00:44:21 volumio volumio[6959]: info: [1763397861468] CoreMusicLibrary::Adding element Webradio Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:44:21 volumio volumio[6959]: info: Initializing BBC Radios Nov 18 00:44:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 18 00:44:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Nov 18 00:44:21 volumio systemd[1]: mpd.service: Deactivated successfully. Nov 18 00:44:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 18 00:44:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Nov 18 00:44:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 18 00:44:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 18 00:44:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 18 00:44:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: Creating Spotify config file Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 18 00:44:21 volumio volumio[6959]: info: [1763397861533] CoreMusicLibrary::Adding element YouTube Music Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 18 00:44:21 volumio volumio[6959]: Cannot find translation for source YouTube Music Nov 18 00:44:21 volumio volumio[6959]: info: AutoStart - onStart Nov 18 00:44:21 volumio volumio[6959]: info: Loading i18n strings for locale zh Nov 18 00:44:21 volumio volumio[6959]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:44:21 volumio volumio[6959]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:44:21 volumio volumio[6959]: info: Loading i18n strings for locale zh Nov 18 00:44:21 volumio volumio[6959]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:44:21 volumio volumio[6959]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:44:21 volumio volumio[6959]: info: Loading i18n strings for locale zh Nov 18 00:44:21 volumio volumio[6959]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:44:21 volumio volumio[6959]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Nov 18 00:44:21 volumio sudo[7152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Nov 18 00:44:21 volumio sudo[7152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 18 00:44:21 volumio volumio[6959]: info: Volumio Calling Home Nov 18 00:44:21 volumio sudo[7156]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Nov 18 00:44:21 volumio sudo[7149]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 18 00:44:21 volumio sudo[7149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 18 00:44:21 volumio sudo[7156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio sudo[7149]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:21 volumio sudo[7156]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:21 volumio sudo[7152]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:21 volumio sudo[7167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Nov 18 00:44:21 volumio sudo[7167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio sudo[7167]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:21 volumio sudo[7170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 18 00:44:21 volumio sudo[7170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio sudo[7175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 18 00:44:21 volumio sudo[7175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio sudo[7172]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 18 00:44:21 volumio sudo[7172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio systemd[1]: Reloading. Nov 18 00:44:21 volumio volumio[6959]: info: Discovery: adding 6d0ee261-5909-4bd4-ad3b-e79afe64531c Nov 18 00:44:21 volumio volumio[6959]: info: Discovery: Found device Volumio Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: Discovery: this is already registered, 6d0ee261-5909-4bd4-ad3b-e79afe64531c Nov 18 00:44:21 volumio volumio[6959]: info: Discovery: Found device Volumio Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio volumio[6959]: info: touch_display: No backlight interface detected. Nov 18 00:44:21 volumio volumio[6959]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Nov 18 00:44:21 volumio volumio[6959]: info: MPD Permissions set Nov 18 00:44:21 volumio volumio[6959]: info: MPD Permissions set Nov 18 00:44:21 volumio volumio[6959]: info: Spotify config file written Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio sudo[7182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 18 00:44:21 volumio sudo[7182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio sudo[7180]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio sudo[7180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 18 00:44:21 volumio volumio[6959]: info: No need to fix Spotify hosts Nov 18 00:44:21 volumio volumio[6959]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 18 00:44:21 volumio sudo[7180]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:21 volumio volumio[6959]: info: touch_display: IframeKeyboardBridge extension installed successfully Nov 18 00:44:21 volumio volumio[6959]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 18 00:44:21 volumio volumio[6959]: info: Starting Shairport Sync Nov 18 00:44:21 volumio sudo[7212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 18 00:44:21 volumio volumio[6959]: info: Starting Shairport Sync Nov 18 00:44:21 volumio sudo[7212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio volumio[6959]: info: Starting Shairport Sync Nov 18 00:44:21 volumio sudo[7212]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:21 volumio sudo[7217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 18 00:44:21 volumio sudo[7217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio volumio[6959]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 18 00:44:21 volumio sudo[7215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 18 00:44:21 volumio sudo[7215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio volumio[6959]: info: ir_controller: LIRC correctly updated. Nov 18 00:44:21 volumio volumio[6959]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Nov 18 00:44:21 volumio sudo[7215]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:21 volumio volumio[6959]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:21 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:21 volumio sudo[7222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 18 00:44:21 volumio sudo[7220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 18 00:44:21 volumio sudo[7222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:21 volumio volumio[6959]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 18 00:44:21 volumio sudo[7220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:22 volumio systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:44:22 volumio systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:44:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:44:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:44:22 volumio mpd[7163]: 2025-11-18T00:44:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 18 00:44:22 volumio sudo[7175]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:22 volumio systemd[1]: Started mpd.service - Music Player Daemon. Nov 18 00:44:22 volumio sudo[7113]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:22 volumio volumio[6959]: info: touch_display: systemctl daemon-reload succeeded. Nov 18 00:44:22 volumio sudo[7122]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:22 volumio sudo[7231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 18 00:44:22 volumio sudo[7231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:22 volumio systemd[1]: Reloading. Nov 18 00:44:22 volumio volumio[6959]: error: MPD error: The expression evaluated to a falsy value: Nov 18 00:44:22 volumio volumio[6959]: assert.ok(self.idling) Nov 18 00:44:22 volumio volumio[6959]: error: The expression evaluated to a falsy value: Nov 18 00:44:22 volumio volumio[6959]: assert.ok(self.idling) Nov 18 00:44:22 volumio volumio[6959]: error: updateQueue error: null Nov 18 00:44:22 volumio volumio[6959]: info: MPD running with PID7163 Nov 18 00:44:22 volumio volumio[6959]: ,establishing connection Nov 18 00:44:22 volumio volumio[6959]: error: updateQueue error: null Nov 18 00:44:22 volumio volumio-remote-updater[1007]: [2025-11-18 00:44:22] [connect] Successful connection Nov 18 00:44:22 volumio volumio-remote-updater[1007]: [2025-11-18 00:44:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1763397862 101 Nov 18 00:44:22 volumio volumio[6959]: 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: 5 Nov 18 00:44:22 volumio systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:44:22 volumio systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:44:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:44:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 18 00:44:22 volumio sudo[7172]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:22 volumio volumio[6959]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 18 00:44:22 volumio sudo[7253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Nov 18 00:44:22 volumio sudo[7253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:23 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Nov 18 00:44:23 volumio lircd[6874]: lircd-0.10.1[6874]: Notice: caught signal Nov 18 00:44:23 volumio lircd-0.10.1[6874]: Notice: caught signal Nov 18 00:44:23 volumio systemd[1]: Stopping lircd.service - Flexible IR remote input/output application support... Nov 18 00:44:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 18 00:44:23 volumio sudo[7231]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:23 volumio volumio[6959]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 18 00:44:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Nov 18 00:44:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 18 00:44:23 volumio systemd[1]: shairport-sync.service: Consumed 1.560s CPU time. Nov 18 00:44:23 volumio volumio[6959]: info: touch_display: Volumio Kiosk started. Nov 18 00:44:23 volumio systemd[1]: lircd.service: Deactivated successfully. Nov 18 00:44:23 volumio systemd[1]: Stopped lircd.service - Flexible IR remote input/output application support. Nov 18 00:44:23 volumio systemd[1]: irexec.service: Deactivated successfully. Nov 18 00:44:23 volumio volumio[6959]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:23 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:23 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Nov 18 00:44:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Nov 18 00:44:23 volumio volumio[6959]: info: Completed starting Core Plugins Nov 18 00:44:23 volumio volumio[6959]: info: ------------------------------------------- Nov 18 00:44:23 volumio volumio[6959]: info: ----- MyVolumio plugins startup ---- Nov 18 00:44:23 volumio volumio[6959]: info: ------------------------------------------- Nov 18 00:44:23 volumio volumio[6959]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 18 00:44:23 volumio volumio[6959]: info: CoreCommandRouter::volumioGetState Nov 18 00:44:23 volumio volumio[6959]: info: CorePlayQueue::getTrack 0 Nov 18 00:44:23 volumio sudo[7170]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:23 volumio volumio[6959]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 18 00:44:23 volumio volumio[6959]: info: touch_display: X display number found: 0 Nov 18 00:44:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 18 00:44:23 volumio go-librespot[7257]: go-librespot daemon starting... Nov 18 00:44:23 volumio sudo[7182]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:23 volumio systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Nov 18 00:44:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 18 00:44:23 volumio sudo[7217]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:23 volumio volumio[6959]: info: Shairport-Sync Started Nov 18 00:44:23 volumio sudo[7220]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:23 volumio sudo[7222]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:23 volumio volumio[6959]: Error adding Membership: Error: addMembership EINVAL Nov 18 00:44:23 volumio go-librespot[7258]: time="2025-11-18T00:44:23+08:00" level=info msg="running go-librespot 0.4.0" Nov 18 00:44:23 volumio go-librespot[7258]: time="2025-11-18T00:44:23+08:00" level=debug msg="app state loaded" Nov 18 00:44:23 volumio go-librespot[7258]: time="2025-11-18T00:44:23+08:00" level=debug msg="stored credentials not found" Nov 18 00:44:23 volumio volumio[6959]: info: Shairport-Sync Started Nov 18 00:44:23 volumio go-librespot[7258]: time="2025-11-18T00:44:23+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 18 00:44:23 volumio volumio[6959]: info: Shairport-Sync Started Nov 18 00:44:23 volumio volumio[6959]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 18 00:44:23 volumio lircd-setup[7259]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. Nov 18 00:44:23 volumio lircd-setup[7259]: parser = configparser.SafeConfigParser() Nov 18 00:44:23 volumio systemd[1]: lircd-setup.service: Deactivated successfully. Nov 18 00:44:23 volumio systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Nov 18 00:44:23 volumio volumio[6959]: info: touch_display: X display number found: 0 Nov 18 00:44:23 volumio volumio[6959]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 18 00:44:23 volumio systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Info: lircd: Opening log, level: Info Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Version: lircd 0.10.1 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: System info: Linux volumio 6.12.47-v8+ #1904 SMP PREEMPT Mon Sep 15 13:10:52 BST 2025 aarch64 GNU/Linux Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Info: Initial device: /dev/lirc0 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Info: [lirc] protocol is enabled Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Info: Initial device: /dev/lirc0 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Info: [lirc] protocol is enabled Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: driver: default Nov 18 00:44:23 volumio lircd[7284]: lircd-0.10.1[7284]: Info: lircd: Opening log, level: Info Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: output: /var/run/lirc/lircd Nov 18 00:44:23 volumio lircd[7284]: lircd-0.10.1[7284]: Notice: Using systemd fd Nov 18 00:44:23 volumio lircd[7284]: lircd-0.10.1[7284]: Warning: Running as root Nov 18 00:44:23 volumio lircd[7284]: lircd-0.10.1[7284]: Info: Using remote: lircd.conf. Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: nodaemon: 1 Nov 18 00:44:23 volumio lircd[7284]: lircd-0.10.1[7284]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 18 00:44:23 volumio systemd[1]: Started lircd.service - Flexible IR remote input/output application support. Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: logfile: syslog Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: immediate-init: 0 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: permission: 666 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: driver-options: Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: listen: 0 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: connect: (null) Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: userelease: 0 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: effective_user: (null) Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: release_suffix: _EVUP Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: allow_simulate: 0 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: repeat_max: 600 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Options: dynamic_codes: (null) Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Current driver: default Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Driver API version: 3 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Driver version: 0.10.0 Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Info: lircd: Opening log, level: Info Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: Using systemd fd Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Warning: Running as root Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Info: Using remote: lircd.conf. Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 18 00:44:23 volumio sudo[7253]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:23 volumio volumio[6959]: info: ir_controller: systemctl restart lircd.service succeeded. Nov 18 00:44:23 volumio sudo[7290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Nov 18 00:44:23 volumio sudo[7290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:23 volumio systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). Nov 18 00:44:23 volumio sudo[7290]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:23 volumio lircd[7284]: lircd-0.10.1[7284]: Notice: accepted new client on /var/run/lirc/lircd Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Notice: accepted new client on /var/run/lirc/lircd Nov 18 00:44:23 volumio lircd[7284]: lircd-0.10.1[7284]: Info: [lirc] protocol is enabled Nov 18 00:44:23 volumio lircd-0.10.1[7284]: Info: [lirc] protocol is enabled Nov 18 00:44:23 volumio volumio[6959]: info: ir_controller: systemctl restart irexec.service succeeded. Nov 18 00:44:25 volumio sudo[7294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Nov 18 00:44:25 volumio sudo[7294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:25 volumio sudo[7297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Nov 18 00:44:25 volumio sudo[7297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:25 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Nov 18 00:44:25 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Nov 18 00:44:25 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Nov 18 00:44:25 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Nov 18 00:44:25 volumio sudo[7294]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:25 volumio mpd_monitor.sh[7299]: MPD Monitor Service: Starting MPD Monitor Service Nov 18 00:44:25 volumio volumio[6959]: info: Successfully started MPD Monitor Nov 18 00:44:25 volumio sudo[7297]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:25 volumio volumio[6959]: info: Successfully started MPD Monitor Nov 18 00:44:26 volumio volumio[6959]: info: go-librespot daemon successfully initialized Nov 18 00:44:27 volumio volumio[6959]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 18 00:44:29 volumio volumio[6959]: info: Initializing connection to go-librespot Websocket Nov 18 00:44:29 volumio go-librespot[7258]: time="2025-11-18T00:44:29+08:00" level=debug msg="new websocket client" Nov 18 00:44:29 volumio volumio[6959]: info: Connection to go-librespot Websocket established Nov 18 00:44:29 volumio sudo[7328]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 18 00:44:29 volumio sudo[7328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:29 volumio sudo[7328]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:29 volumio sudo[7330]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 18 00:44:29 volumio sudo[7330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:29 volumio sudo[7330]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:29 volumio sudo[7335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 18 00:44:29 volumio sudo[7335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:29 volumio volumio[6959]: info: Volumio called home Nov 18 00:44:29 volumio sudo[7335]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:29 volumio volumio[6959]: info: Upmpdcli Daemon Started Nov 18 00:44:29 volumio go-librespot[7258]: time="2025-11-18T00:44:29+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2025-11-18T00:44:29+08:00 is before 2025-12-08T00:00:00Z" Nov 18 00:44:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 18 00:44:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 18 00:44:29 volumio volumio[6959]: info: Connection to go-librespot Websocket closed Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 18 00:44:32 volumio volumio[6959]: info: Adding plugin bluetooth to MyMusic Plugins Nov 18 00:44:32 volumio volumio[6959]: info: Adding plugin multiroom to MyMusic Plugins Nov 18 00:44:32 volumio volumio[6959]: info: Adding plugin metavolumio to MyMusic Plugins Nov 18 00:44:32 volumio volumio[6959]: info: Adding plugin cd_controller to MyMusic Plugins Nov 18 00:44:32 volumio volumio[6959]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 18 00:44:32 volumio volumio[6959]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 18 00:44:32 volumio volumio[6959]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 18 00:44:32 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:44:32 volumio volumio[6959]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 18 00:44:32 volumio volumio[6959]: info: Starting MyVolumio Remote Streaming Endpoints Nov 18 00:44:32 volumio volumio[6959]: info: MyVolumio login type: Token Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 18 00:44:32 volumio volumio[6959]: info: Streaming services startup Nov 18 00:44:32 volumio volumio[6959]: info: Starting Streaming Daemon Nov 18 00:44:32 volumio sudo[7338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 18 00:44:32 volumio sudo[7338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 18 00:44:32 volumio volumio[6959]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 18 00:44:32 volumio sudo[7338]: pam_unix(sudo:session): session closed for user root Nov 18 00:44:32 volumio volumio[6959]: info: Getting Spotify volume Nov 18 00:44:32 volumio volumio[6959]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 18 00:44:32 volumio volumio[6959]: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 18 00:44:32 volumio volumio[6959]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Nov 18 00:44:32 volumio volumio[6959]: errno: -111, Nov 18 00:44:32 volumio volumio[6959]: code: 'ECONNREFUSED', Nov 18 00:44:32 volumio volumio[6959]: syscall: 'connect', Nov 18 00:44:32 volumio volumio[6959]: address: '127.0.0.1', Nov 18 00:44:32 volumio volumio[6959]: port: 9879, Nov 18 00:44:32 volumio volumio[6959]: response: undefined Nov 18 00:44:32 volumio volumio[6959]: } Nov 18 00:44:32 volumio volumio[6959]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 18 00:44:32 volumio sudo[7358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-18 00:43' Nov 18 00:44:32 volumio sudo[7358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:31:40 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5fbe1bcf6936184712979c0bfaf061f2"