-- Logs begin at Thu 2025-07-10 14:49:44 CST, end at Thu 2025-07-10 14:51:40 CST. -- Jul 10 14:50:00 v3tb16pii systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 10 14:50:00 v3tb16pii startx[1311]: X.Org X Server 1.20.4 Jul 10 14:50:00 v3tb16pii startx[1311]: X Protocol Version 11, Revision 0 Jul 10 14:50:00 v3tb16pii startx[1311]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Jul 10 14:50:00 v3tb16pii startx[1311]: Current Operating System: Linux v3tb16pii 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Jul 10 14:50:00 v3tb16pii startx[1311]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=99f8f95b-c99d-4f8a-8e3a-2c94b956b604 bootpart=UUID=6C07-EB09 datapart=UUID=87616cd0-a8a9-47dd-ba39-fe6bd3d507cd 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 Jul 10 14:50:00 v3tb16pii startx[1311]: Build Date: 18 December 2021 09:05:36AM Jul 10 14:50:00 v3tb16pii startx[1311]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jul 10 14:50:00 v3tb16pii startx[1311]: Current version of pixman: 0.36.0 Jul 10 14:50:00 v3tb16pii startx[1311]: Before reporting problems, check http://wiki.x.org Jul 10 14:50:00 v3tb16pii startx[1311]: to make sure that you have the latest version. Jul 10 14:50:00 v3tb16pii startx[1311]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 10 14:50:00 v3tb16pii startx[1311]: (++) from command line, (!!) notice, (II) informational, Jul 10 14:50:00 v3tb16pii startx[1311]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 10 14:50:00 v3tb16pii startx[1311]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jul 10 14:50:00 2025 Jul 10 14:50:00 v3tb16pii startx[1311]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 10 14:50:00 v3tb16pii startx[1311]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 10 14:50:00 v3tb16pii winbindd[1304]: [2025/07/10 14:50:00.145111, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 10 14:50:00 v3tb16pii winbindd[1304]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 10 14:50:00 v3tb16pii winbindd[1304]: [2025/07/10 14:50:00.154420, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 14:50:00 v3tb16pii systemd[1]: Started Samba Winbind Daemon. Jul 10 14:50:00 v3tb16pii winbindd[1304]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 10 14:50:00 v3tb16pii systemd[1]: Starting Samba SMB Daemon... Jul 10 14:50:00 v3tb16pii kernel: broken atomic modeset userspace detected, disabling atomic Jul 10 14:50:00 v3tb16pii smbd[1381]: [2025/07/10 14:50:00.643433, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 14:50:00 v3tb16pii systemd[1]: Started Samba SMB Daemon. Jul 10 14:50:00 v3tb16pii smbd[1381]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 10 14:50:00 v3tb16pii startx[1311]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jul 10 14:50:00 v3tb16pii systemd[1]: soundcard-init.service: Succeeded. Jul 10 14:50:01 v3tb16pii ntpd[1103]: Listen normally on 3 wlan0 192.168.2.66:123 Jul 10 14:50:01 v3tb16pii ntpd[1103]: new interface(s) found: waking up resolver Jul 10 14:50:01 v3tb16pii volumio-remote-updater[864]: [2025-07-10 14:50:01] [connect] Successful connection Jul 10 14:50:02 v3tb16pii volumio[1309]: info: ------------------------------------------- Jul 10 14:50:02 v3tb16pii volumio[1309]: info: ----- Volumio3 ---- Jul 10 14:50:02 v3tb16pii volumio[1309]: info: ------------------------------------------- Jul 10 14:50:02 v3tb16pii volumio[1309]: info: ----- System startup ---- Jul 10 14:50:02 v3tb16pii volumio[1309]: info: ------------------------------------------- Jul 10 14:50:02 v3tb16pii volumio-time-update[879]: volumio-time-update-util: Fetching time from Volumio... Jul 10 14:50:03 v3tb16pii volumio[1309]: info: MYVOLUMIO Environment detected Jul 10 14:50:03 v3tb16pii volumio-time-update[879]: volumio-time-update-util: Setting system time to: 2025-07-10 14:50:02 Jul 10 14:50:03 v3tb16pii sudo[1553]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-07-10 14:50:02 Jul 10 14:50:03 v3tb16pii sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii dbus-daemon[880]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1554 comm="timedatectl set-time 2025-07-10 14:50:02 ") Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Plugin folders cleanup Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Scanning into folder /volumio/app/plugins/ Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Scanning category audio_interface Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Scanning category miscellanea Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Scanning category music_service Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Scanning category plugins.json Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Scanning category system_controller Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Scanning category user_interface Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Scanning into folder /data/plugins/ Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Scanning category user_interface Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Plugin folders cleanup completed Jul 10 14:50:03 v3tb16pii volumio[1309]: info: ------------------------------------------- Jul 10 14:50:03 v3tb16pii volumio[1309]: info: ----- Core plugins startup ---- Jul 10 14:50:03 v3tb16pii volumio[1309]: info: ------------------------------------------- Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugins from folder /volumio/app/plugins/ Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Adding plugin upnp to MyMusic Plugins Jul 10 14:50:03 v3tb16pii systemd[1]: Starting Time & Date Service... Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugins from folder /data/plugins/ Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "system"... Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "appearance"... Jul 10 14:50:03 v3tb16pii dbus-daemon[880]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 10 14:50:03 v3tb16pii systemd[1]: Started Time & Date Service. Jul 10 14:50:02 v3tb16pii systemd-timedated[1555]: Changed local time to Thu Jul 10 14:50:02 2025 Jul 10 14:50:02 v3tb16pii sudo[1553]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:02 v3tb16pii volumio-time-update[879]: volumio-time-update-util: System time updated successfully. Jul 10 14:50:02 v3tb16pii systemd[1]: Started Volumio Time Update Utility. Jul 10 14:50:02 v3tb16pii systemd[1]: Reached target Multi-User System. Jul 10 14:50:02 v3tb16pii systemd[1]: Reached target Graphical Interface. Jul 10 14:50:02 v3tb16pii systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 10 14:50:02 v3tb16pii systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 10 14:50:02 v3tb16pii systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 10 14:50:02 v3tb16pii systemd[1]: Startup finished in 10.641s (firmware) + 12.633s (loader) + 13.921s (kernel) + 19.966s (userspace) = 57.163s. Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "network"... Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Refreshing Cached IP Addresses Jul 10 14:50:03 v3tb16pii sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 14:50:03 v3tb16pii sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 14:50:03 v3tb16pii sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii sudo[1581]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "services"... Jul 10 14:50:03 v3tb16pii sudo[1578]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "alsa_controller"... Jul 10 14:50:03 v3tb16pii sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 10 14:50:03 v3tb16pii sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "wizard"... Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "networkfs"... Jul 10 14:50:03 v3tb16pii sudo[1625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.2.1/Hatich500G\MusicBox /mnt/NAS/Music_Box Jul 10 14:50:03 v3tb16pii sudo[1625]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.2.1/Hatich500G\DTSMusic /mnt/NAS/DTS-Music Jul 10 14:50:03 v3tb16pii sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.2.1/Hatich500G\MusicMP3 /mnt/NAS/MusicMP3 Jul 10 14:50:03 v3tb16pii sudo[1638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.2.1/Hatich500G\Youtube /mnt/NAS/Youtube Jul 10 14:50:03 v3tb16pii sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii kernel: FS-Cache: Loaded Jul 10 14:50:03 v3tb16pii kernel: Key type dns_resolver registered Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Starting Udev Watcher for removable devices Jul 10 14:50:03 v3tb16pii sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.2.1/Hatich500G\QQsong /mnt/NAS/QQ音乐下载 Jul 10 14:50:03 v3tb16pii sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.2.1/Hatich500G\SACD /mnt/NAS/SACD Jul 10 14:50:03 v3tb16pii sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:03 v3tb16pii volumio[1309]: error: Cannot associate FS Label, not mounting Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Ignoring mount for partition: ANDROIDIA Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Ignoring mount for partition: volumio Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Ignoring mount for partition: volumio_data Jul 10 14:50:03 v3tb16pii volumio[1309]: error: Cannot associate FS Label, not mounting Jul 10 14:50:03 v3tb16pii kernel: Key type cifs.spnego registered Jul 10 14:50:03 v3tb16pii kernel: Key type cifs.idmap registered Jul 10 14:50:03 v3tb16pii kernel: CIFS: Attempting to mount //192.168.2.1/Hatich500G/MusicMP3 Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Ignoring mount for partition: 57f8f4bc-abf4-655f-bf67-946fc0f9f25b Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Ignoring mount for partition: SYSTEM Jul 10 14:50:03 v3tb16pii volumio[1309]: error: Cannot associate FS Label, not mounting Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Ignoring mount for partition: Windows Jul 10 14:50:03 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "volumio_command_line_client"... Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "upnp"... Jul 10 14:50:03 v3tb16pii volumio[1309]: info: [1752130203931] Starting Upmpd Daemon Jul 10 14:50:03 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "my_music"... Jul 10 14:50:03 v3tb16pii volumio[1309]: info: Loading plugin "mpd"... Jul 10 14:50:04 v3tb16pii volumio[1309]: info: Loading plugin "upnp_browser"... Jul 10 14:50:04 v3tb16pii volumio[1309]: info: Loading plugin "alarm-clock"... Jul 10 14:50:04 v3tb16pii sudo[1598]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:04 v3tb16pii volumio[1309]: info: Loading plugin "airplay_emulation"... Jul 10 14:50:04 v3tb16pii volumio[1309]: info: Starting Shairport Sync Jul 10 14:50:04 v3tb16pii volumio[1309]: info: Loading plugin "last_100"... Jul 10 14:50:04 v3tb16pii volumio[1309]: info: Loading plugin "webradio"... Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading plugin "i2s_dacs"... Jul 10 14:50:05 v3tb16pii volumio[1309]: info: I2S DAC not set, start Auto-detection Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading plugin "volumiodiscovery"... Jul 10 14:50:05 v3tb16pii volumio[1309]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 14:50:05 v3tb16pii volumio[1309]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 14:50:05 v3tb16pii volumio[1309]: *** WARNING *** For more information see Jul 10 14:50:05 v3tb16pii volumio[1309]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 14:50:05 v3tb16pii node[1309]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 14:50:05 v3tb16pii volumio[1309]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 14:50:05 v3tb16pii volumio[1309]: *** WARNING *** For more information see Jul 10 14:50:05 v3tb16pii node[1309]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 14:50:05 v3tb16pii node[1309]: *** WARNING *** For more information see Jul 10 14:50:05 v3tb16pii node[1309]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 14:50:05 v3tb16pii node[1309]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 14:50:05 v3tb16pii node[1309]: *** WARNING *** For more information see Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Discovery: Started advertising with name: V3TB16PII Jul 10 14:50:05 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading plugin "peppy_screensaver"... Jul 10 14:50:05 v3tb16pii sudo[1638]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:05 v3tb16pii kernel: CIFS: Attempting to mount //192.168.2.1/Hatich500G/Youtube Jul 10 14:50:05 v3tb16pii sudo[1642]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:05 v3tb16pii kernel: CIFS: Attempting to mount //192.168.2.1/Hatich500G/DTSMusic Jul 10 14:50:05 v3tb16pii sudo[1635]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:05 v3tb16pii kernel: CIFS: Attempting to mount //192.168.2.1/Hatich500G/QQsong Jul 10 14:50:05 v3tb16pii kernel: CIFS: Attempting to mount //192.168.2.1/Hatich500G/SACD Jul 10 14:50:05 v3tb16pii sudo[1663]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:05 v3tb16pii kernel: CIFS: Attempting to mount //192.168.2.1/Hatich500G/MusicBox Jul 10 14:50:05 v3tb16pii sudo[1670]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:05 v3tb16pii sudo[1625]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Applying required configuration parameters for plugin peppy_screensaver Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading plugin "outputs"... Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading plugin "albumart"... Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Plugin example_plugin is not enabled Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading plugin "inputs"... Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading plugin "updater_comm"... Jul 10 14:50:05 v3tb16pii volumio[1309]: Forking 3 albumart workers Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Plugin mpdemulation is not enabled Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading plugin "rest_api"... Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading plugin "websocket"... Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Starting Socket.io Server version 2.3.0 Jul 10 14:50:05 v3tb16pii volumio[1309]: info: Loading i18n strings for locale zh Jul 10 14:50:05 v3tb16pii volumio[1309]: Updating browse sources language Jul 10 14:50:05 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::initPlayerControls Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: Express server listening on port 3000 Jul 10 14:50:06 v3tb16pii volumio[1309]: [Metrics] WebUI: 6s 564.76ms Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Setting Device type: x86 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreStateMachine::resetVolumioState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreStateMachine::getcurrentVolume Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 14:50:06 v3tb16pii volumio[1309]: Starting albumart workers Jul 10 14:50:06 v3tb16pii volumio[1309]: Starting albumart workers Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: Starting albumart workers Jul 10 14:50:06 v3tb16pii volumio[1309]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Completed loading Core Plugins Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Preparing to generate the ALSA configuration file Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Volumio Network Manager: Network status updated: 2 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 10 14:50:06 v3tb16pii volumio[1309]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Reading ALSA contributions from plugins. Jul 10 14:50:06 v3tb16pii volumio[1309]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:06 v3tb16pii volumio[1309]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Reloading queue from file Jul 10 14:50:06 v3tb16pii volumio[1309]: info: VolumeController:: Volume=undefined Mute =false Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreStateMachine::pushState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreStateMachine::updateTrackBlock Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrackBlock Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreStateMachine::setRepeat true single undefined Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreStateMachine::pushState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreStateMachine::setRandom false Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreStateMachine::pushState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushState Jul 10 14:50:06 v3tb16pii volumio[1309]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Discovery: adding 37abb759-91d0-4155-a2c6-19488efc1057 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Discovery: Found device V3TB16PII Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: VolumeController:: Volume=undefined Mute =false Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreStateMachine::pushState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushState Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Asound.conf file unchanged, so no further update is needed Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Output device has changed, restarting MPD Jul 10 14:50:06 v3tb16pii sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 14:50:06 v3tb16pii sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Output device has changed, restarting Shairport Sync Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:06 v3tb16pii sudo[1806]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:06 v3tb16pii sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 14:50:06 v3tb16pii sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:06 v3tb16pii systemd[1]: Stopping Music Player Daemon... Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 14:50:06 v3tb16pii volumio[1309]: info: ___________ START PLUGINS ___________ Jul 10 14:50:06 v3tb16pii volumio[1309]: info: ControllerMpd::onStart: Initializing MPD Jul 10 14:50:06 v3tb16pii volumio[1309]: info: Creating MPD Configuration file Jul 10 14:50:06 v3tb16pii sudo[1815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 14:50:06 v3tb16pii sudo[1815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:50:06 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 14:50:06 v3tb16pii sudo[1815]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:07 v3tb16pii volumio[1309]: info: [1752130206999] CoreMusicLibrary::Adding element 媒体服务器 Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 14:50:07 v3tb16pii sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 14:50:07 v3tb16pii sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:07 v3tb16pii systemd[1]: mpd.service: Succeeded. Jul 10 14:50:07 v3tb16pii systemd[1]: Stopped Music Player Daemon. Jul 10 14:50:07 v3tb16pii systemd[1]: Starting Music Player Daemon... Jul 10 14:50:07 v3tb16pii systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 10 14:50:07 v3tb16pii systemd[1]: mpd.service: Succeeded. Jul 10 14:50:07 v3tb16pii systemd[1]: Stopped Music Player Daemon. Jul 10 14:50:07 v3tb16pii systemd[1]: Starting Music Player Daemon... Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 14:50:07 v3tb16pii volumio[1309]: info: [1752130207103] CoreMusicLibrary::Adding element Last_100 Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 14:50:07 v3tb16pii volumio[1309]: info: [1752130207107] CoreMusicLibrary::Adding element Webradio Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Initializing BBC Radios Jul 10 14:50:07 v3tb16pii sudo[1824]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 14:50:07 v3tb16pii volumio[1309]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 10 14:50:07 v3tb16pii sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:07 v3tb16pii sudo[1824]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Loading i18n strings for locale zh Jul 10 14:50:07 v3tb16pii volumio[1309]: info: /tmp/myfifo created Jul 10 14:50:07 v3tb16pii volumio[1309]: info: /tmp/myfifosa created Jul 10 14:50:07 v3tb16pii sudo[1845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jul 10 14:50:07 v3tb16pii sudo[1845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:07 v3tb16pii sudo[1845]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:07 v3tb16pii volumio[1309]: info: snd-dummy loaded Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Volumio Calling Home Jul 10 14:50:07 v3tb16pii volumio[1309]: info: MPD Permissions set Jul 10 14:50:07 v3tb16pii volumio[1309]: info: MPD Permissions set Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:07 v3tb16pii volumio[1309]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 10 14:50:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Starting Shairport Sync Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Starting Shairport Sync Jul 10 14:50:07 v3tb16pii sudo[1887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 14:50:07 v3tb16pii sudo[1887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Starting Shairport Sync Jul 10 14:50:07 v3tb16pii sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 14:50:07 v3tb16pii sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:07 v3tb16pii systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 14:50:07 v3tb16pii systemd[1]: shairport-sync.service: Succeeded. Jul 10 14:50:07 v3tb16pii systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 14:50:07 v3tb16pii volumio[1309]: info: Starting Shairport Sync Jul 10 14:50:07 v3tb16pii sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jul 10 14:50:07 v3tb16pii sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:07 v3tb16pii sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 14:50:07 v3tb16pii sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:07 v3tb16pii systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 14:50:07 v3tb16pii sudo[1887]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:07 v3tb16pii systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 14:50:07 v3tb16pii systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 10 14:50:07 v3tb16pii systemd[1]: shairport-sync.service: Succeeded. Jul 10 14:50:07 v3tb16pii systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 14:50:07 v3tb16pii sudo[1886]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:08 v3tb16pii systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 14:50:08 v3tb16pii sudo[1889]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:08 v3tb16pii systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 14:50:08 v3tb16pii systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 10 14:50:08 v3tb16pii systemd[1]: shairport-sync.service: Succeeded. Jul 10 14:50:08 v3tb16pii systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 14:50:08 v3tb16pii volumio[1309]: info: Shairport-Sync Started Jul 10 14:50:08 v3tb16pii systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 14:50:08 v3tb16pii volumio[1309]: Error adding Membership: Error: addMembership EINVAL Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jul 10 14:50:08 v3tb16pii sudo[1898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 14:50:08 v3tb16pii sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:08 v3tb16pii sudo[1893]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:08 v3tb16pii volumio[1309]: info: Shairport-Sync Started Jul 10 14:50:08 v3tb16pii systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:08 v3tb16pii systemd[1]: shairport-sync.service: Succeeded. Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:08 v3tb16pii systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:08 v3tb16pii sudo[1904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 14:50:08 v3tb16pii sudo[1904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:08 v3tb16pii sudo[1904]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:08 v3tb16pii systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 14:50:08 v3tb16pii sudo[1898]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:08 v3tb16pii mpd[1832]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 14:50:08 v3tb16pii mpd[1832]: output: No 'audio_output' defined in config file Jul 10 14:50:08 v3tb16pii volumio[1309]: info: Shairport-Sync Started Jul 10 14:50:08 v3tb16pii volumio[1309]: info: MPD Permissions set Jul 10 14:50:08 v3tb16pii mpd[1832]: output: Successfully detected a sndio audio device Jul 10 14:50:08 v3tb16pii mpd[1832]: zeroconf: No global port, disabling zeroconf Jul 10 14:50:08 v3tb16pii systemd[1]: Started Music Player Daemon. Jul 10 14:50:08 v3tb16pii sudo[1817]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:08 v3tb16pii sudo[1808]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:08 v3tb16pii volumio[1309]: info: Completed starting Core Plugins Jul 10 14:50:08 v3tb16pii volumio[1309]: info: ------------------------------------------- Jul 10 14:50:08 v3tb16pii volumio[1309]: info: ----- MyVolumio plugins startup ---- Jul 10 14:50:08 v3tb16pii volumio[1309]: info: ------------------------------------------- Jul 10 14:50:08 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 10 14:50:08 v3tb16pii volumio[1309]: error: MPD error: The expression evaluated to a falsy value: Jul 10 14:50:08 v3tb16pii volumio[1309]: assert.ok(self.idling) Jul 10 14:50:08 v3tb16pii volumio[1309]: error: The expression evaluated to a falsy value: Jul 10 14:50:08 v3tb16pii volumio[1309]: assert.ok(self.idling) Jul 10 14:50:08 v3tb16pii volumio[1309]: info: MPD running with PID1832 Jul 10 14:50:08 v3tb16pii volumio[1309]: ,establishing connection Jul 10 14:50:08 v3tb16pii volumio[1309]: error: updateQueue error: null Jul 10 14:50:08 v3tb16pii volumio[1309]: error: updateQueue error: null Jul 10 14:50:08 v3tb16pii volumio[1309]: info: Volumio called home Jul 10 14:50:08 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 10 14:50:08 v3tb16pii sudo[1921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 14:50:08 v3tb16pii sudo[1921]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:08 v3tb16pii systemd[1]: Stopping Music Player Daemon... Jul 10 14:50:08 v3tb16pii systemd[1]: mpd.service: Succeeded. Jul 10 14:50:08 v3tb16pii systemd[1]: Stopped Music Player Daemon. Jul 10 14:50:08 v3tb16pii systemd[1]: Starting Music Player Daemon... Jul 10 14:50:08 v3tb16pii sudo[1923]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 14:50:08 v3tb16pii sudo[1923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:08 v3tb16pii sudo[1923]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:08 v3tb16pii volumio[1309]: 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: 3 Jul 10 14:50:09 v3tb16pii mpd[1925]: Jul 10 14:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 14:50:09 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 14:50:09 v3tb16pii systemd[1]: Started Music Player Daemon. Jul 10 14:50:09 v3tb16pii sudo[1921]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:09 v3tb16pii volumio[1309]: error: updateQueue error: null Jul 10 14:50:10 v3tb16pii volumio[1309]: 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: 4 Jul 10 14:50:10 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 10 14:50:11 v3tb16pii volumio[1309]: info: Received Get System Info Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 14:50:11 v3tb16pii volumio[1309]: info: Discovery: Getting this device information Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:11 v3tb16pii volumio[1309]: info: Listing playlists Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:11 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:14 v3tb16pii sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 14:50:14 v3tb16pii sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:14 v3tb16pii sudo[1948]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:14 v3tb16pii sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 14:50:14 v3tb16pii sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:14 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 14:50:14 v3tb16pii volumio[1309]: info: Received Get System Info Jul 10 14:50:14 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 14:50:14 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 14:50:14 v3tb16pii volumio[1309]: info: Discovery: Getting this device information Jul 10 14:50:14 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:14 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:14 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 14:50:14 v3tb16pii sudo[1952]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:14 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 10 14:50:14 v3tb16pii sudo[1956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 10 14:50:14 v3tb16pii sudo[1956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:14 v3tb16pii volumio[1309]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 10 14:50:14 v3tb16pii systemd[1]: Started UPnP Renderer front-end to MPD. Jul 10 14:50:14 v3tb16pii sudo[1956]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:14 v3tb16pii volumio-remote-updater[864]: [2025-07-10 14:50:14] [connect] Successful connection Jul 10 14:50:15 v3tb16pii systemd[1]: systemd-fsckd.service: Succeeded. Jul 10 14:50:15 v3tb16pii volumio[1309]: info: Upmpdcli Daemon Started Jul 10 14:50:15 v3tb16pii volumio-remote-updater[864]: [2025-07-10 14:50:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752130214 101 Jul 10 14:50:15 v3tb16pii volumio[1309]: 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 Jul 10 14:50:15 v3tb16pii volumio[1958]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 10 14:50:17 v3tb16pii volumio[1958]: ...............................................................................++++ Jul 10 14:50:17 v3tb16pii systemd[1]: systemd-hostnamed.service: Succeeded. Jul 10 14:50:17 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 10 14:50:18 v3tb16pii volumio[1309]: info: Adding plugin bluetooth to MyMusic Plugins Jul 10 14:50:18 v3tb16pii volumio[1309]: info: Adding plugin multiroom to MyMusic Plugins Jul 10 14:50:18 v3tb16pii volumio[1309]: info: Adding plugin metavolumio to MyMusic Plugins Jul 10 14:50:18 v3tb16pii volumio[1309]: info: Adding plugin cd_controller to MyMusic Plugins Jul 10 14:50:18 v3tb16pii volumio[1309]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 10 14:50:18 v3tb16pii volumio[1309]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 10 14:50:18 v3tb16pii volumio[1309]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 10 14:50:18 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 10 14:50:19 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 10 14:50:19 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 10 14:50:19 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:19 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:19 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:19 v3tb16pii volumio[1309]: info: Starting MyVolumio Remote Streaming Endpoints Jul 10 14:50:19 v3tb16pii volumio[1309]: info: MyVolumio login type: Token Jul 10 14:50:19 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 10 14:50:19 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 10 14:50:20 v3tb16pii volumio[1309]: info: Starting Streaming Service Transparent Proxy Jul 10 14:50:20 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 10 14:50:20 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 10 14:50:20 v3tb16pii volumio[1309]: info: Streaming services startup Jul 10 14:50:20 v3tb16pii volumio[1309]: info: Starting Streaming Daemon Jul 10 14:50:20 v3tb16pii sudo[2176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 10 14:50:20 v3tb16pii sudo[2176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 14:50:20 v3tb16pii sudo[2176]: pam_unix(sudo:session): session closed for user root Jul 10 14:50:20 v3tb16pii volumio[1309]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 10 14:50:21 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 10 14:50:21 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 14:50:21 v3tb16pii volumio[1309]: error: Cannot start Volumio Streaming Daemon Jul 10 14:50:21 v3tb16pii volumio[1309]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 10 14:50:21 v3tb16pii volumio[1309]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 10 14:50:21 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:21 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:21 v3tb16pii volumio[1309]: STREAMING PROXY: Starting server on port 3245 Jul 10 14:50:21 v3tb16pii volumio[1309]: Node JS runtime: 14 Jul 10 14:50:21 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:21 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:24 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetQueue Jul 10 14:50:24 v3tb16pii volumio[1309]: info: CoreStateMachine::getQueue Jul 10 14:50:24 v3tb16pii volumio[1309]: info: CorePlayQueue::getQueue Jul 10 14:50:26 v3tb16pii volumio[1958]: ..................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................++++ Jul 10 14:50:26 v3tb16pii volumio[1958]: e is 65537 (0x010001) Jul 10 14:50:26 v3tb16pii volumio[1958]: writing RSA key Jul 10 14:50:32 v3tb16pii systemd[1]: systemd-timedated.service: Succeeded. Jul 10 14:50:33 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:33 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:34 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 14:50:35 v3tb16pii volumio[1309]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 10 14:50:35 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:35 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:36 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:36 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:37 v3tb16pii volumio[1309]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 10 14:50:37 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:37 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 14:50:37 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:37 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 14:50:37 v3tb16pii volumio-remote-updater[864]: No test mode Jul 10 14:50:37 v3tb16pii volumio-remote-updater[864]: No alpha test mode Jul 10 14:50:37 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 10 14:50:38 v3tb16pii volumio[1309]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 10 14:50:38 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 10 14:50:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 10 14:50:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 10 14:50:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 10 14:50:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetBrowseSources Jul 10 14:50:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 14:50:40 v3tb16pii volumio[1309]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 10 14:50:40 v3tb16pii volumio[1309]: info: Completed starting MyVolumio Plugin Jul 10 14:50:40 v3tb16pii volumio[1309]: [Metrics] CommandRouter: 40s 517.47ms Jul 10 14:50:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumiosetStartupVolume Jul 10 14:50:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 14:50:40 v3tb16pii volumio[1309]: info: VolumeController:: Setting startup Volume 95 Jul 10 14:50:40 v3tb16pii volumio[1309]: info: VolumeController::SetAlsaVolume95 Jul 10 14:50:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:50:41 v3tb16pii volumio[1309]: info: CoreCommandRouter::Close All Modals sent Jul 10 14:50:41 v3tb16pii volumio[1309]: info: CoreCommandRouter::Close All Modals sent Jul 10 14:50:41 v3tb16pii volumio[1309]: info: CoreStateMachine::pushState Jul 10 14:50:41 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:41 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 14:50:41 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushState Jul 10 14:50:41 v3tb16pii volumio[1309]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 10 14:50:42 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 10 14:50:42 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 14:50:42 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 10 14:50:44 v3tb16pii volumio[1309]: info: Preload queue cleared Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::ClearQueue Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::stop Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CorePlayQueue::clearPlayQueue Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CorePlayQueue::saveQueue Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushQueue Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::addQueueItems Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CorePlayQueue::addQueueItems Jul 10 14:50:44 v3tb16pii volumio[1309]: info: Preload queue cleared Jul 10 14:50:44 v3tb16pii volumio[1309]: info: Adding Item to queue: music-library/NAS/Music_Box/00CD/《再叙云之南》 Jul 10 14:50:44 v3tb16pii volumio[1309]: info: Exploding uri music-library/NAS/Music_Box/00CD/《再叙云之南》 in service mpd Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F01.%20%E8%B7%B3%E5%8A%A8%E7%9A%84%E7%AF%9D%E7%81%AB.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/01. 跳动的篝火.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F02.%20%E5%8F%A4%E8%80%81%E7%9A%84%E4%BC%A0%E8%AF%B4.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/02. 古老的传说.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F03.%20%E5%A4%95%E9%98%B3%E4%B8%8B%E7%9A%84%E6%9D%91%E5%BA%84.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/03. 夕阳下的村庄.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F04.%20%E8%A5%BF%E5%AF%86.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/04. 西密.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F05.%20%E6%9D%91%E5%BA%84%E7%9A%84%E5%91%A2%E5%96%83.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/05. 村庄的呢喃.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F06.%20%E6%BA%AA%E8%BE%B9%E7%9A%84%E5%AC%89%E6%88%8F.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/06. 溪边的嬉戏.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F07.%20%E8%B7%B3%E5%8A%A8%E7%9A%84%E5%9B%BE%E8%85%BE.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/07. 跳动的图腾.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F08.%20%E5%A4%95%E9%98%B3%E7%89%A7%E6%AD%8C.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/08. 夕阳牧歌.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F09.%20%E9%BE%99%E6%BD%AD.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/09. 龙潭.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F10.%20%E5%8F%B8%E5%B2%97%E9%87%8C%E6%83%85.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/10. 司岗里情.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ALBUMART /albumart?cacheid=12&web=%E5%B4%94%E5%BC%A6%E4%BA%AE/%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97/extralarge&path=%2Fmnt%2FNAS%2FMusic_Box%2F00CD%2F%E3%80%8A%E5%86%8D%E5%8F%99%E4%BA%91%E4%B9%8B%E5%8D%97%E3%80%8B%2F11.%20%E5%9C%A8%E5%B1%B1%E9%A1%B6%E4%B8%8A.ape&metadata=false Jul 10 14:50:44 v3tb16pii volumio[1309]: info: URI /mnt/NAS/Music_Box/00CD/《再叙云之南》/11. 在山顶上.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushQueue Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CorePlayQueue::saveQueue Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::updateTrackBlock Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrackBlock Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPlay Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::play index 0 Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::stop Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::play index undefined Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CoreStateMachine::startPlaybackTimer Jul 10 14:50:44 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:44 v3tb16pii volumio[1309]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music_Box/00CD/《再叙云之南》/01. 跳动的篝火.ape Jul 10 14:50:44 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 14:50:44 v3tb16pii volumio[1309]: info: sendMpdCommand stop took 8 milliseconds Jul 10 14:50:44 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 14:50:44 v3tb16pii volumio[1309]: info: Jul 10 14:50:44 v3tb16pii volumio[1309]: ---------------------------- MPD announces system playlist update Jul 10 14:50:44 v3tb16pii volumio[1309]: info: Ignoring MPD Status Update Jul 10 14:50:44 v3tb16pii volumio[1309]: info: sendMpdCommand clear took 4 milliseconds Jul 10 14:50:44 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music_Box/00CD/《再叙云之南》/01. 跳动的篝火.ape" Jul 10 14:50:44 v3tb16pii volumio[1309]: error: updateQueue error: null Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ------------------------------ 8ms Jul 10 14:50:44 v3tb16pii volumio[1309]: info: sendMpdCommand add "NAS/Music_Box/00CD/《再叙云之南》/01. 跳动的篝火.ape" took 5 milliseconds Jul 10 14:50:44 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand play Jul 10 14:50:44 v3tb16pii volumio[1309]: info: Jul 10 14:50:44 v3tb16pii volumio[1309]: ---------------------------- MPD announces system playlist update Jul 10 14:50:44 v3tb16pii volumio[1309]: info: Ignoring MPD Status Update Jul 10 14:50:44 v3tb16pii volumio[1309]: info: sendMpdCommand play took 3 milliseconds Jul 10 14:50:44 v3tb16pii volumio[1309]: info: ------------------------------ 4ms Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:45 v3tb16pii volumio[1309]: info: Jul 10 14:50:45 v3tb16pii volumio[1309]: ---------------------------- MPD announces state update: player Jul 10 14:50:45 v3tb16pii volumio[1309]: info: ControllerMpd::getState Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Jul 10 14:50:45 v3tb16pii volumio[1309]: info: Jul 10 14:50:45 v3tb16pii volumio[1309]: ---------------------------- MPD announces state update: player Jul 10 14:50:45 v3tb16pii volumio[1309]: info: sendMpdCommand status took 29 milliseconds Jul 10 14:50:45 v3tb16pii volumio[1309]: info: ControllerMpd::getState Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: ControllerMpd::parseState Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 14:50:45 v3tb16pii volumio[1309]: info: sendMpdCommand status took 7 milliseconds Jul 10 14:50:45 v3tb16pii volumio[1309]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: ControllerMpd::parseState Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: ControllerMpd::parseTrackInfo Jul 10 14:50:45 v3tb16pii volumio[1309]: info: ControllerMpd::pushState Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreCommandRouter::servicePushState Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"跳动的篝火","artist":"崔弦亮","album":"再叙云之南","uri":"NAS/Music_Box/00CD/《再叙云之南》/01. 跳动的篝火.ape","trackType":"ape"} Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: CURRENT POSITION 0 Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreStateMachine::syncState stateService play Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreStateMachine::syncState currentStatus stop Jul 10 14:50:45 v3tb16pii volumio[1309]: info: ------------------------------ 56ms Jul 10 14:50:45 v3tb16pii volumio[1309]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: ControllerMpd::parseTrackInfo Jul 10 14:50:45 v3tb16pii volumio[1309]: info: ControllerMpd::pushState Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreCommandRouter::servicePushState Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"跳动的篝火","artist":"崔弦亮","album":"再叙云之南","uri":"NAS/Music_Box/00CD/《再叙云之南》/01. 跳动的篝火.ape","trackType":"ape"} Jul 10 14:50:45 v3tb16pii volumio[1309]: verbose: CURRENT POSITION 0 Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreStateMachine::syncState stateService play Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreStateMachine::syncState currentStatus play Jul 10 14:50:45 v3tb16pii volumio[1309]: info: Received an update from plugin. extracting info from payload Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreStateMachine::pushState Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushState Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreStateMachine::pushState Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:45 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushState Jul 10 14:50:45 v3tb16pii volumio[1309]: info: ------------------------------ 76ms Jul 10 14:50:48 v3tb16pii volumio[1309]: info: BOOT COMPLETED Jul 10 14:50:51 v3tb16pii volumio[1309]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 10 14:50:51 v3tb16pii volumio[1309]: info: Retrying Login Due to Network Error Jul 10 14:50:51 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:51 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:53 v3tb16pii ntpd[1103]: Soliciting pool server 84.16.73.33 Jul 10 14:50:55 v3tb16pii ntpd[1103]: Soliciting pool server 202.118.1.81 Jul 10 14:50:55 v3tb16pii ntpd[1103]: Soliciting pool server 162.159.200.123 Jul 10 14:50:56 v3tb16pii ntpd[1103]: Soliciting pool server 162.159.200.1 Jul 10 14:50:56 v3tb16pii ntpd[1103]: Soliciting pool server 95.111.202.5 Jul 10 14:50:57 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:57 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:57 v3tb16pii ntpd[1103]: Soliciting pool server 139.199.214.202 Jul 10 14:50:57 v3tb16pii ntpd[1103]: Soliciting pool server 119.28.206.193 Jul 10 14:50:58 v3tb16pii volumio[1309]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Jul 10 14:50:58 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:50:58 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:50:58 v3tb16pii ntpd[1103]: Soliciting pool server 119.28.183.184 Jul 10 14:50:58 v3tb16pii ntpd[1103]: Soliciting pool server 95.111.202.5 Jul 10 14:50:59 v3tb16pii ntpd[1103]: Soliciting pool server 193.182.111.141 Jul 10 14:50:59 v3tb16pii ntpd[1103]: Soliciting pool server 2402:4e00:c050:2400:32d6:6914:76b4:1 Jul 10 14:51:00 v3tb16pii ntpd[1103]: Soliciting pool server 193.182.111.12 Jul 10 14:51:01 v3tb16pii volumio[1309]: info: MyVolumio login type: Token Jul 10 14:51:03 v3tb16pii volumio[1309]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Jul 10 14:51:03 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:51:03 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:51:05 v3tb16pii ntpd[1103]: receive: Unexpected origin timestamp 0xec19e158.8dc2a25b does not match aorg 0000000000.00000000 from server@193.182.111.12 xmt 0xec19e159.2e7710e1 Jul 10 14:51:05 v3tb16pii ntpd[1103]: receive: Unexpected origin timestamp 0xec19e158.8dcfa51d does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xec19e159.1b457760 Jul 10 14:51:07 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:51:07 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:51:22 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:51:22 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:51:31 v3tb16pii volumio[1309]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 10 14:51:31 v3tb16pii volumio[1309]: info: Retrying Login Due to Network Error Jul 10 14:51:33 v3tb16pii kernel: usb 1-4.4: USB disconnect, device number 4 Jul 10 14:51:33 v3tb16pii volumio[1309]: info: Jul 10 14:51:33 v3tb16pii volumio[1309]: ---------------------------- USB Audio Device Detached Jul 10 14:51:33 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jul 10 14:51:33 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 14:51:33 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 10 14:51:37 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:51:37 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:51:39 v3tb16pii kernel: usb 1-4.4: new high-speed USB device number 5 using xhci_hcd Jul 10 14:51:39 v3tb16pii kernel: usb 1-4.4: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 6.c2 Jul 10 14:51:39 v3tb16pii kernel: usb 1-4.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jul 10 14:51:39 v3tb16pii kernel: usb 1-4.4: Product: NX4 DSD Jul 10 14:51:39 v3tb16pii kernel: usb 1-4.4: Manufacturer: Topping Jul 10 14:51:39 v3tb16pii kernel: usb 1-4.4: 1:3 : unsupported format bits 0x100000000 Jul 10 14:51:39 v3tb16pii volumio[1309]: info: Jul 10 14:51:39 v3tb16pii volumio[1309]: ---------------------------- USB Audio Device Attached Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::Close All Modals sent Jul 10 14:51:39 v3tb16pii volumio[1309]: info: Preparing to save Alsa Options, stopping services first Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioGetState Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPause Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreStateMachine::pause Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreStateMachine::stPlaybackTimer Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreStateMachine::servicePause Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::servicePause Jul 10 14:51:39 v3tb16pii volumio[1309]: info: ControllerMpd::pause Jul 10 14:51:39 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand pause Jul 10 14:51:39 v3tb16pii volumio[1309]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"NX4 DSD","alsacard":"DSD"},"i2s":false} Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 14:51:39 v3tb16pii volumio[1309]: info: Setting mixer NX4 DSD for card NX4 DSD Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 10 14:51:39 v3tb16pii volumio[1309]: info: Updating Volume Controller Parameters: Device: 5 Name: NX4 DSD Mixer: NX4 DSD Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 10 14:51:39 v3tb16pii volumio[1309]: info: Disabling external Volume Control Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 14:51:39 v3tb16pii volumio[1309]: info: Preparing to generate the ALSA configuration file Jul 10 14:51:39 v3tb16pii volumio[1309]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jul 10 14:51:39 v3tb16pii volumio[1309]: info: Reading ALSA contributions from plugins. Jul 10 14:51:39 v3tb16pii volumio[1309]: info: Jul 10 14:51:39 v3tb16pii volumio[1309]: ---------------------------- MPD announces state update: player Jul 10 14:51:39 v3tb16pii volumio[1309]: info: sendMpdCommand pause took 225 milliseconds Jul 10 14:51:39 v3tb16pii volumio[1309]: info: ControllerMpd::getState Jul 10 14:51:39 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Jul 10 14:51:39 v3tb16pii volumio[1309]: info: sendMpdCommand status took 26 milliseconds Jul 10 14:51:39 v3tb16pii volumio[1309]: verbose: ControllerMpd::parseState Jul 10 14:51:39 v3tb16pii volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 14:51:39 v3tb16pii volumio[1309]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 14:51:39 v3tb16pii volumio[1309]: verbose: ControllerMpd::parseTrackInfo Jul 10 14:51:39 v3tb16pii volumio[1309]: info: ControllerMpd::pushState Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::servicePushState Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:51:39 v3tb16pii volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":54867,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"跳动的篝火","artist":"崔弦亮","album":"再叙云之南","uri":"NAS/Music_Box/00CD/《再叙云之南》/01. 跳动的篝火.ape","trackType":"ape"} Jul 10 14:51:39 v3tb16pii volumio[1309]: verbose: CURRENT POSITION 0 Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreStateMachine::syncState stateService pause Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreStateMachine::pushState Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CorePlayQueue::getTrack 0 Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreCommandRouter::volumioPushState Jul 10 14:51:39 v3tb16pii volumio[1309]: info: CoreStateMachine::stPlaybackTimer Jul 10 14:51:40 v3tb16pii volumio[1309]: info: ------------------------------ 62ms Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 10 14:51:40 v3tb16pii volumio[1309]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 10 14:51:40 v3tb16pii volumio[1309]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 14:51:40 v3tb16pii volumio[1309]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jul 10 14:51:40 v3tb16pii volumio[1309]: at Object.readdirSync (fs.js:1048:3) Jul 10 14:51:40 v3tb16pii volumio[1309]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jul 10 14:51:40 v3tb16pii volumio[1309]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Jul 10 14:51:40 v3tb16pii volumio[1309]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Jul 10 14:51:40 v3tb16pii volumio[1309]: errno: -20, Jul 10 14:51:40 v3tb16pii volumio[1309]: syscall: 'scandir', Jul 10 14:51:40 v3tb16pii volumio[1309]: code: 'ENOTDIR', Jul 10 14:51:40 v3tb16pii volumio[1309]: path: '/tmp/peppyrunning' Jul 10 14:51:40 v3tb16pii volumio[1309]: } Jul 10 14:51:40 v3tb16pii volumio[1309]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 14:51:40 v3tb16pii sudo[2628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-10 14:50 Jul 10 14:51:40 v3tb16pii sudo[2628]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:41:06 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="097e6b8b481766e7a0f9527e4a34a29b"