-- Logs begin at Mon 2025-09-15 23:54:19 CST, end at Mon 2025-09-15 23:57:12 CST. -- Sep 15 23:56:19 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 23:56:22 volumio volumio[1020]: info: CoreCommandRouter::Close All Modals sent Sep 15 23:56:27 volumio volumio-remote-updater[640]: No test mode Sep 15 23:56:27 volumio volumio-remote-updater[640]: No alpha test mode Sep 15 23:56:27 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 15 23:56:28 volumio volumio[1020]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 15 23:56:28 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 15 23:56:28 volumio volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 23:56:28 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 15 23:56:28 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 15 23:56:28 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 15 23:56:28 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 15 23:56:29 volumio volumio[1020]: info: Received Get System Version Sep 15 23:56:29 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 23:56:30 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Sep 15 23:57:01 volumio volumio[1020]: info: CALLMETHOD: system_controller system saveHDMISettings [object Object] Sep 15 23:57:01 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , saveHDMISettings Sep 15 23:57:01 volumio sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Sep 15 23:57:01 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:01 volumio systemd[1]: Stopping Start Volumio Kiosk... Sep 15 23:57:01 volumio startx[1022]: xinit: connection to X server lost Sep 15 23:57:01 volumio startx[1022]: [1B blob data] Sep 15 23:57:01 volumio startx[1022]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Sep 15 23:57:02 volumio startx[1022]: xinit: unexpected signal 15 Sep 15 23:57:02 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Sep 15 23:57:02 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Sep 15 23:57:02 volumio systemd[1]: Stopped Start Volumio Kiosk. Sep 15 23:57:02 volumio systemd[1]: Started Start Volumio Kiosk. Sep 15 23:57:02 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:02 volumio sudo[2334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable volumio-kiosk.service Sep 15 23:57:02 volumio sudo[2334]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:02 volumio systemd[1]: Reloading. Sep 15 23:57:02 volumio startx[2331]: X.Org X Server 1.20.4 Sep 15 23:57:02 volumio startx[2331]: X Protocol Version 11, Revision 0 Sep 15 23:57:02 volumio startx[2331]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Sep 15 23:57:02 volumio startx[2331]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Sep 15 23:57:02 volumio startx[2331]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=cb6ab0ff-0489-407c-8c10-3cb82ffdd444 bootpart=UUID=4989-A492 datapart=UUID=9af694f0-1f78-4a3e-9c74-91a2531800f7 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Sep 15 23:57:02 volumio startx[2331]: Build Date: 18 December 2021 09:05:36AM Sep 15 23:57:02 volumio startx[2331]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Sep 15 23:57:02 volumio startx[2331]: Current version of pixman: 0.36.0 Sep 15 23:57:02 volumio startx[2331]: Before reporting problems, check http://wiki.x.org Sep 15 23:57:02 volumio startx[2331]: to make sure that you have the latest version. Sep 15 23:57:02 volumio startx[2331]: Markers: (--) probed, (**) from config file, (==) default setting, Sep 15 23:57:02 volumio startx[2331]: (++) from command line, (!!) notice, (II) informational, Sep 15 23:57:02 volumio startx[2331]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Sep 15 23:57:02 volumio startx[2331]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Sep 15 23:57:02 2025 Sep 15 23:57:02 volumio startx[2331]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Sep 15 23:57:02 volumio volumio[1020]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 23:57:02 volumio volumio[1020]: Error: connect ETIMEDOUT 69.171.229.11:443 Sep 15 23:57:02 volumio volumio[1020]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 15 23:57:02 volumio volumio[1020]: errno: -110, Sep 15 23:57:02 volumio volumio[1020]: code: 'ETIMEDOUT', Sep 15 23:57:02 volumio volumio[1020]: syscall: 'connect', Sep 15 23:57:02 volumio volumio[1020]: address: '69.171.229.11', Sep 15 23:57:02 volumio volumio[1020]: port: 443 Sep 15 23:57:02 volumio volumio[1020]: } Sep 15 23:57:02 volumio volumio[1020]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 23:57:02 volumio kernel: broken atomic modeset userspace detected, disabling atomic Sep 15 23:57:02 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 15 23:57:02 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 15 23:57:02 volumio sudo[2379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 23:56 Sep 15 23:57:02 volumio sudo[2379]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:02 volumio sudo[2334]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:02 volumio sudo[2379]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:03 volumio startx[2331]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Sep 15 23:57:04 volumio volumio-remote-updater[640]: [2025-09-15 23:57:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 15 23:57:04 volumio volumio-remote-updater[640]: [2025-09-15 23:57:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 15 23:57:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 15 23:57:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 15 23:57:04 volumio systemd[1]: Started dynamicswap service. Sep 15 23:57:04 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 23:57:04 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 15 23:57:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 15 23:57:05 volumio systemd[1]: Started dynamicswap service. Sep 15 23:57:05 volumio systemd[1]: Stopped Volumio Backend Module. Sep 15 23:57:05 volumio systemd[1]: Started Volumio Backend Module. Sep 15 23:57:05 volumio systemd[1]: dynamicswap.service: Succeeded. Sep 15 23:57:06 volumio volumio[2530]: info: ------------------------------------------- Sep 15 23:57:06 volumio volumio[2530]: info: ----- Volumio3 ---- Sep 15 23:57:06 volumio volumio[2530]: info: ------------------------------------------- Sep 15 23:57:06 volumio volumio[2530]: info: ----- System startup ---- Sep 15 23:57:06 volumio volumio[2530]: info: ------------------------------------------- Sep 15 23:57:06 volumio volumio[2530]: info: MYVOLUMIO Environment detected Sep 15 23:57:06 volumio volumio[2530]: info: Plugin folders cleanup Sep 15 23:57:06 volumio volumio[2530]: info: Scanning into folder /volumio/app/plugins/ Sep 15 23:57:06 volumio volumio[2530]: info: Scanning category audio_interface Sep 15 23:57:06 volumio volumio[2530]: info: Scanning category miscellanea Sep 15 23:57:06 volumio volumio[2530]: info: Scanning category music_service Sep 15 23:57:06 volumio volumio[2530]: info: Scanning category plugins.json Sep 15 23:57:06 volumio volumio[2530]: info: Scanning category system_controller Sep 15 23:57:06 volumio volumio[2530]: info: Scanning category user_interface Sep 15 23:57:06 volumio volumio[2530]: info: Scanning into folder /data/plugins/ Sep 15 23:57:06 volumio volumio[2530]: info: Plugin folders cleanup completed Sep 15 23:57:06 volumio volumio[2530]: info: ------------------------------------------- Sep 15 23:57:06 volumio volumio[2530]: info: ----- Core plugins startup ---- Sep 15 23:57:06 volumio volumio[2530]: info: ------------------------------------------- Sep 15 23:57:06 volumio volumio[2530]: info: Loading plugins from folder /volumio/app/plugins/ Sep 15 23:57:06 volumio volumio[2530]: info: Adding plugin upnp to MyMusic Plugins Sep 15 23:57:06 volumio volumio[2530]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 15 23:57:06 volumio volumio[2530]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 15 23:57:06 volumio volumio[2530]: info: Loading plugins from folder /data/plugins/ Sep 15 23:57:06 volumio volumio[2530]: info: Loading plugin "system"... Sep 15 23:57:06 volumio volumio[2530]: info: Loading plugin "appearance"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "network"... Sep 15 23:57:07 volumio volumio[2530]: info: Refreshing Cached IP Addresses Sep 15 23:57:07 volumio sudo[2587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 23:57:07 volumio sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:07 volumio sudo[2587]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:07 volumio sudo[2589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 23:57:07 volumio sudo[2589]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:07 volumio sudo[2589]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "services"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "alsa_controller"... Sep 15 23:57:07 volumio sudo[2598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 15 23:57:07 volumio sudo[2598]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:07 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "wizard"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "networkfs"... Sep 15 23:57:07 volumio volumio[2530]: info: Starting Udev Watcher for removable devices Sep 15 23:57:07 volumio volumio[2530]: info: Ignoring mount for partition: boot Sep 15 23:57:07 volumio volumio[2530]: info: Ignoring mount for partition: volumio Sep 15 23:57:07 volumio volumio[2530]: info: Ignoring mount for partition: volumio_data Sep 15 23:57:07 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "volumio_command_line_client"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "upnp"... Sep 15 23:57:07 volumio volumio[2530]: info: [1757951827348] Starting Upmpd Daemon Sep 15 23:57:07 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "my_music"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "mpd"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "upnp_browser"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "alarm-clock"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "airplay_emulation"... Sep 15 23:57:07 volumio volumio[2530]: info: Starting Shairport Sync Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "last_100"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "webradio"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "i2s_dacs"... Sep 15 23:57:07 volumio volumio[2530]: info: I2S DAC not set, start Auto-detection Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "volumiodiscovery"... Sep 15 23:57:07 volumio volumio[2530]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 23:57:07 volumio volumio[2530]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 23:57:07 volumio volumio[2530]: *** WARNING *** For more information see Sep 15 23:57:07 volumio volumio[2530]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 23:57:07 volumio volumio[2530]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 23:57:07 volumio volumio[2530]: *** WARNING *** For more information see Sep 15 23:57:07 volumio node[2530]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 15 23:57:07 volumio node[2530]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 23:57:07 volumio node[2530]: *** WARNING *** For more information see Sep 15 23:57:07 volumio node[2530]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 15 23:57:07 volumio node[2530]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 15 23:57:07 volumio node[2530]: *** WARNING *** For more information see Sep 15 23:57:07 volumio volumio[2530]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 15 23:57:07 volumio volumio[2530]: info: Discovery: Started advertising with name: Volumio Sep 15 23:57:07 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "outputs"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "albumart"... Sep 15 23:57:07 volumio volumio[2530]: info: Plugin example_plugin is not enabled Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "inputs"... Sep 15 23:57:07 volumio volumio[2530]: info: Loading plugin "updater_comm"... Sep 15 23:57:08 volumio volumio[2530]: info: Plugin mpdemulation is not enabled Sep 15 23:57:08 volumio volumio[2530]: info: Loading plugin "rest_api"... Sep 15 23:57:08 volumio volumio[2530]: Forking 1 albumart workers Sep 15 23:57:08 volumio volumio[2530]: info: Loading plugin "websocket"... Sep 15 23:57:08 volumio volumio[2530]: info: Starting Socket.io Server version 2.3.0 Sep 15 23:57:08 volumio volumio[2530]: info: Loading i18n strings for locale zh Sep 15 23:57:08 volumio volumio[2530]: Updating browse sources language Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::initPlayerControls Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: Express server listening on port 3000 Sep 15 23:57:08 volumio volumio[2530]: [Metrics] WebUI: 2s 566.58ms Sep 15 23:57:08 volumio volumio[2530]: info: Setting Device type: x86 Sep 15 23:57:08 volumio volumio[2530]: info: CoreStateMachine::resetVolumioState Sep 15 23:57:08 volumio volumio[2530]: info: CoreStateMachine::getcurrentVolume Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 15 23:57:08 volumio volumio[2530]: info: Completed loading Core Plugins Sep 15 23:57:08 volumio volumio[2530]: info: Preparing to generate the ALSA configuration file Sep 15 23:57:08 volumio volumio[2530]: info: Volumio Network Manager: Network status updated: 2 Sep 15 23:57:08 volumio volumio[2530]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 15 23:57:08 volumio volumio[2530]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Sep 15 23:57:08 volumio volumio[2530]: Unable to parse: Sep 15 23:57:08 volumio volumio[2530]: Simple mixer control 'IEC958',0 Sep 15 23:57:08 volumio volumio[2530]: Capabilities: pswitch pswitch-joined Sep 15 23:57:08 volumio volumio[2530]: Playback channels: Mono Sep 15 23:57:08 volumio volumio[2530]: Mono: Playback [on] Sep 15 23:57:08 volumio volumio[2530]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 15 23:57:08 volumio volumio[2530]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Sep 15 23:57:08 volumio volumio[2530]: Starting albumart workers Sep 15 23:57:08 volumio volumio[2530]: info: Reloading queue from file Sep 15 23:57:08 volumio volumio[2530]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Sep 15 23:57:08 volumio volumio[2530]: Unable to parse: Sep 15 23:57:08 volumio volumio[2530]: Simple mixer control 'IEC958',0 Sep 15 23:57:08 volumio volumio[2530]: Capabilities: pswitch pswitch-joined Sep 15 23:57:08 volumio volumio[2530]: Playback channels: Mono Sep 15 23:57:08 volumio volumio[2530]: Mono: Playback [on] Sep 15 23:57:08 volumio volumio[2530]: info: VolumeController:: Volume=undefined Mute =false Sep 15 23:57:08 volumio volumio[2530]: info: CoreStateMachine::pushState Sep 15 23:57:08 volumio volumio[2530]: info: CorePlayQueue::getTrack 0 Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::volumioPushState Sep 15 23:57:08 volumio volumio[2530]: info: CoreStateMachine::updateTrackBlock Sep 15 23:57:08 volumio volumio[2530]: info: CorePlayQueue::getTrackBlock Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::volumioRetrievevolume Sep 15 23:57:08 volumio volumio[2530]: info: Asound.conf file unchanged, so no further update is needed Sep 15 23:57:08 volumio volumio[2530]: info: Output device has changed, restarting MPD Sep 15 23:57:08 volumio volumio[2530]: info: Output device has changed, restarting Shairport Sync Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 23:57:08 volumio sudo[2638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 23:57:08 volumio sudo[2640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 23:57:08 volumio sudo[2638]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:08 volumio sudo[2640]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:08 volumio sudo[2638]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:08 volumio systemd[1]: Stopping Music Player Daemon... Sep 15 23:57:08 volumio volumio[2530]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 23:57:08 volumio volumio[2530]: info: ___________ START PLUGINS ___________ Sep 15 23:57:08 volumio volumio[2530]: info: ControllerMpd::onStart: Initializing MPD Sep 15 23:57:08 volumio volumio[2530]: info: Creating MPD Configuration file Sep 15 23:57:08 volumio systemd[1]: mpd.service: Succeeded. Sep 15 23:57:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 23:57:08 volumio systemd[1]: Starting Music Player Daemon... Sep 15 23:57:08 volumio sudo[2648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 15 23:57:08 volumio sudo[2648]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:08 volumio sudo[2648]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 23:57:08 volumio volumio[2530]: info: [1757951828874] CoreMusicLibrary::Adding element 媒体服务器 Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 23:57:08 volumio sudo[2651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 15 23:57:08 volumio sudo[2651]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 23:57:08 volumio sudo[2647]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 23:57:08 volumio sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:08 volumio sudo[2647]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 15 23:57:08 volumio systemd[1]: mpd.service: Succeeded. Sep 15 23:57:08 volumio systemd[1]: Stopped Music Player Daemon. Sep 15 23:57:08 volumio systemd[1]: Starting Music Player Daemon... Sep 15 23:57:08 volumio volumio[2530]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:08 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 23:57:09 volumio volumio[2530]: info: [1757951829007] CoreMusicLibrary::Adding element Last_100 Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 15 23:57:09 volumio volumio[2530]: info: [1757951829009] CoreMusicLibrary::Adding element Webradio Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 23:57:09 volumio volumio[2530]: info: Initializing BBC Radios Sep 15 23:57:09 volumio sudo[2658]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 15 23:57:09 volumio sudo[2658]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:09 volumio sudo[2658]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: Volumio Calling Home Sep 15 23:57:09 volumio volumio[2530]: info: CoreStateMachine::setRepeat null single undefined Sep 15 23:57:09 volumio volumio[2530]: info: CoreStateMachine::pushState Sep 15 23:57:09 volumio volumio[2530]: info: CorePlayQueue::getTrack 0 Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::volumioPushState Sep 15 23:57:09 volumio volumio[2530]: info: CoreStateMachine::setRandom null Sep 15 23:57:09 volumio volumio[2530]: info: CoreStateMachine::pushState Sep 15 23:57:09 volumio volumio[2530]: info: CorePlayQueue::getTrack 0 Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::volumioPushState Sep 15 23:57:09 volumio volumio[2530]: info: MPD Permissions set Sep 15 23:57:09 volumio volumio[2530]: info: MPD Permissions set Sep 15 23:57:09 volumio volumio[2530]: Unable to parse: Sep 15 23:57:09 volumio volumio[2530]: Simple mixer control 'IEC958',0 Sep 15 23:57:09 volumio volumio[2530]: Capabilities: pswitch pswitch-joined Sep 15 23:57:09 volumio volumio[2530]: Playback channels: Mono Sep 15 23:57:09 volumio volumio[2530]: Mono: Playback [on] Sep 15 23:57:09 volumio volumio[2530]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 15 23:57:09 volumio volumio[2530]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 15 23:57:09 volumio volumio[2530]: Unable to parse: Sep 15 23:57:09 volumio volumio[2530]: Simple mixer control 'IEC958',0 Sep 15 23:57:09 volumio volumio[2530]: Capabilities: pswitch pswitch-joined Sep 15 23:57:09 volumio volumio[2530]: Playback channels: Mono Sep 15 23:57:09 volumio volumio[2530]: Mono: Playback [on] Sep 15 23:57:09 volumio volumio[2530]: info: VolumeController:: Volume=undefined Mute =false Sep 15 23:57:09 volumio volumio[2530]: info: CoreStateMachine::pushState Sep 15 23:57:09 volumio volumio[2530]: info: CorePlayQueue::getTrack 0 Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::volumioPushState Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 15 23:57:09 volumio volumio-remote-updater[640]: [2025-09-15 23:57:09] [connect] Successful connection Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 15 23:57:09 volumio volumio[2530]: info: Volumio called home Sep 15 23:57:09 volumio volumio-remote-updater[640]: [2025-09-15 23:57:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1757951829 101 Sep 15 23:57:09 volumio volumio[2530]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Sep 15 23:57:09 volumio volumio[2530]: info: Starting Shairport Sync Sep 15 23:57:09 volumio volumio[2530]: info: Starting Shairport Sync Sep 15 23:57:09 volumio volumio[2530]: info: Starting Shairport Sync Sep 15 23:57:09 volumio mpd[2670]: Sep 15 23:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 15 23:57:09 volumio sudo[2700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 23:57:09 volumio sudo[2700]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:09 volumio systemd[1]: Started Music Player Daemon. Sep 15 23:57:09 volumio sudo[2651]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 23:57:09 volumio sudo[2640]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:09 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 23:57:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 23:57:09 volumio sudo[2702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 23:57:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 23:57:09 volumio volumio[2530]: info: Discovery: adding 893f6f98-762b-4578-9670-7bc2ce92ff03 Sep 15 23:57:09 volumio sudo[2702]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:09 volumio volumio[2530]: info: Discovery: Found device Volumio Sep 15 23:57:09 volumio volumio[2530]: info: CoreCommandRouter::volumioGetState Sep 15 23:57:09 volumio sudo[2700]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:09 volumio sudo[2708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 15 23:57:09 volumio sudo[2708]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 23:57:09 volumio volumio[2530]: info: CorePlayQueue::getTrack 0 Sep 15 23:57:10 volumio sudo[2598]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 23:57:10 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 23:57:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::volumioGetState Sep 15 23:57:10 volumio volumio[2530]: info: CorePlayQueue::getTrack 0 Sep 15 23:57:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 23:57:10 volumio sudo[2702]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 15 23:57:10 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 15 23:57:10 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 15 23:57:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 15 23:57:10 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 15 23:57:10 volumio sudo[2708]: pam_unix(sudo:session): session closed for user root Sep 15 23:57:10 volumio volumio[2530]: info: Shairport-Sync Started Sep 15 23:57:10 volumio volumio[2530]: Error adding Membership: Error: addMembership EINVAL Sep 15 23:57:10 volumio volumio[2530]: info: Completed starting Core Plugins Sep 15 23:57:10 volumio volumio[2530]: info: ------------------------------------------- Sep 15 23:57:10 volumio volumio[2530]: info: ----- MyVolumio plugins startup ---- Sep 15 23:57:10 volumio volumio[2530]: info: ------------------------------------------- Sep 15 23:57:10 volumio volumio[2530]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 15 23:57:10 volumio volumio[2530]: info: Shairport-Sync Started Sep 15 23:57:10 volumio volumio[2530]: info: Shairport-Sync Started Sep 15 23:57:10 volumio volumio[2530]: error: MPD error: The expression evaluated to a falsy value: Sep 15 23:57:10 volumio volumio[2530]: assert.ok(self.idling) Sep 15 23:57:10 volumio volumio[2530]: error: The expression evaluated to a falsy value: Sep 15 23:57:10 volumio volumio[2530]: assert.ok(self.idling) Sep 15 23:57:10 volumio volumio[2530]: error: updateQueue error: null Sep 15 23:57:10 volumio volumio[2530]: info: MPD running with PID2670 Sep 15 23:57:10 volumio volumio[2530]: ,establishing connection Sep 15 23:57:10 volumio volumio[2530]: error: updateQueue error: null Sep 15 23:57:10 volumio volumio[2530]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 15 23:57:10 volumio volumio[2530]: verbose: New Socket.io Connection to 192.168.1.100 from 192.168.1.105 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::volumioGetState Sep 15 23:57:10 volumio volumio[2530]: info: CorePlayQueue::getTrack 0 Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::volumioGetVisibleSources Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 23:57:10 volumio volumio[2530]: info: Listing playlists Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::volumioGetQueue Sep 15 23:57:10 volumio volumio[2530]: info: CoreStateMachine::getQueue Sep 15 23:57:10 volumio volumio[2530]: info: CorePlayQueue::getQueue Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::volumioGetState Sep 15 23:57:10 volumio volumio[2530]: info: CorePlayQueue::getTrack 0 Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::volumioGetVisibleSources Sep 15 23:57:10 volumio volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 23:57:11 volumio volumio[2530]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 15 23:57:12 volumio volumio[2530]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 23:57:12 volumio volumio[2530]: Error: connect ECONNREFUSED 108.160.170.44:443 Sep 15 23:57:12 volumio volumio[2530]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 15 23:57:12 volumio volumio[2530]: errno: -111, Sep 15 23:57:12 volumio volumio[2530]: code: 'ECONNREFUSED', Sep 15 23:57:12 volumio volumio[2530]: syscall: 'connect', Sep 15 23:57:12 volumio volumio[2530]: address: '108.160.170.44', Sep 15 23:57:12 volumio volumio[2530]: port: 443 Sep 15 23:57:12 volumio volumio[2530]: } Sep 15 23:57:12 volumio volumio[2530]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 23:57:12 volumio sudo[2901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 23:56 Sep 15 23:57:12 volumio sudo[2901]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"