-- Logs begin at Sat 2024-10-12 10:32:12 CST, end at Sat 2024-10-12 10:36:47 CST. -- Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "network"... Oct 12 10:35:00 volumio volumio[1109]: info: Refreshing Cached IP Addresses Oct 12 10:35:00 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 10:35:00 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:00 volumio sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 10:35:00 volumio sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "services"... Oct 12 10:35:00 volumio sudo[1328]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "alsa_controller"... Oct 12 10:35:00 volumio sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 12 10:35:00 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:00 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:00 volumio volumio[1109]: warn: Unable to locate the audio output device HDMI. Please configure a valid output device. Oct 12 10:35:00 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "wizard"... Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "networkfs"... Oct 12 10:35:00 volumio volumio[1109]: info: Starting Udev Watcher for removable devices Oct 12 10:35:00 volumio volumio[1109]: info: Ignoring mount for partition: boot Oct 12 10:35:00 volumio volumio[1109]: info: Ignoring mount for partition: volumio Oct 12 10:35:00 volumio volumio[1109]: info: Ignoring mount for partition: volumio_data Oct 12 10:35:00 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "volumio_command_line_client"... Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "upnp"... Oct 12 10:35:00 volumio volumio[1109]: info: [1728700500645] Starting Upmpd Daemon Oct 12 10:35:00 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "my_music"... Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "mpd"... Oct 12 10:35:00 volumio ntpd[1098]: Soliciting pool server 162.159.200.1 Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "upnp_browser"... Oct 12 10:35:00 volumio ntpd[1098]: Soliciting pool server 202.112.29.82 Oct 12 10:35:00 volumio volumio[1109]: info: Loading plugin "alarm-clock"... Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "airplay_emulation"... Oct 12 10:35:01 volumio volumio[1109]: info: Starting Shairport Sync Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "last_100"... Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "webradio"... Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "i2s_dacs"... Oct 12 10:35:01 volumio volumio[1109]: info: I2S DAC not set, start Auto-detection Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "volumiodiscovery"... Oct 12 10:35:01 volumio volumio[1109]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 10:35:01 volumio volumio[1109]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 10:35:01 volumio volumio[1109]: *** WARNING *** For more information see Oct 12 10:35:01 volumio node[1109]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 10:35:01 volumio volumio[1109]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 10:35:01 volumio volumio[1109]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 10:35:01 volumio volumio[1109]: *** WARNING *** For more information see Oct 12 10:35:01 volumio node[1109]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 10:35:01 volumio node[1109]: *** WARNING *** For more information see Oct 12 10:35:01 volumio node[1109]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 10:35:01 volumio node[1109]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 10:35:01 volumio node[1109]: *** WARNING *** For more information see Oct 12 10:35:01 volumio volumio[1109]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 12 10:35:01 volumio volumio[1109]: info: Discovery: Started advertising with name: Volumio Oct 12 10:35:01 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "jellyfin"... Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "autostart"... Oct 12 10:35:01 volumio volumio[1109]: info: Applying required configuration parameters for plugin autostart Oct 12 10:35:01 volumio volumio[1109]: info: AutoStart - onVolumioStart - read config.json Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "peppy_screensaver"... Oct 12 10:35:01 volumio ntpd[1098]: Soliciting pool server 84.16.67.12 Oct 12 10:35:01 volumio volumio[1109]: info: Applying required configuration parameters for plugin peppy_screensaver Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "outputs"... Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "albumart"... Oct 12 10:35:01 volumio volumio[1109]: info: Plugin example_plugin is not enabled Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "inputs"... Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "updater_comm"... Oct 12 10:35:01 volumio volumio[1109]: Forking 3 albumart workers Oct 12 10:35:01 volumio volumio[1109]: info: Plugin mpdemulation is not enabled Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "rest_api"... Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "websocket"... Oct 12 10:35:01 volumio ntpd[1098]: Soliciting pool server 84.16.67.12 Oct 12 10:35:01 volumio volumio[1109]: info: Starting Socket.io Server version 2.3.0 Oct 12 10:35:01 volumio volumio[1109]: info: Loading plugin "RoonBridge"... Oct 12 10:35:01 volumio nmbd[913]: [2024/10/12 10:35:01.916240, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 12 10:35:01 volumio nmbd[913]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 12 10:35:01 volumio systemd[1]: Started Samba NMB Daemon. Oct 12 10:35:01 volumio systemd[1]: Starting Samba Winbind Daemon... Oct 12 10:35:01 volumio volumio[1109]: Starting albumart workers Oct 12 10:35:02 volumio volumio[1109]: Starting albumart workers Oct 12 10:35:02 volumio volumio[1109]: Starting albumart workers Oct 12 10:35:02 volumio volumio[1109]: info: Applying required configuration parameters for plugin RoonBridge Oct 12 10:35:02 volumio volumio[1109]: info: Loading plugin "minidlna"... Oct 12 10:35:02 volumio winbindd[1450]: [2024/10/12 10:35:02.056796, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 12 10:35:02 volumio winbindd[1450]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 12 10:35:02 volumio winbindd[1450]: [2024/10/12 10:35:02.059715, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 12 10:35:02 volumio systemd[1]: Started Samba Winbind Daemon. Oct 12 10:35:02 volumio winbindd[1450]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 12 10:35:02 volumio systemd[1]: Starting Samba SMB Daemon... Oct 12 10:35:02 volumio volumio[1109]: info: Applying required configuration parameters for plugin minidlna Oct 12 10:35:02 volumio nmbd[913]: [2024/10/12 10:35:02.131935, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) Oct 12 10:35:02 volumio nmbd[913]: my_name_register_failed: Failed to register my name VOLUMIO<20> on subnet 192.168.31.152. Oct 12 10:35:02 volumio nmbd[913]: [2024/10/12 10:35:02.132034, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) Oct 12 10:35:02 volumio nmbd[913]: standard_fail_register: Failed to register/refresh name VOLUMIO<20> on subnet 192.168.31.152 Oct 12 10:35:02 volumio nmbd[913]: [2024/10/12 10:35:02.132071, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) Oct 12 10:35:02 volumio nmbd[913]: my_name_register_failed: Failed to register my name VOLUMIO<03> on subnet 192.168.31.152. Oct 12 10:35:02 volumio nmbd[913]: [2024/10/12 10:35:02.132102, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) Oct 12 10:35:02 volumio nmbd[913]: standard_fail_register: Failed to register/refresh name VOLUMIO<03> on subnet 192.168.31.152 Oct 12 10:35:02 volumio nmbd[913]: [2024/10/12 10:35:02.162042, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 12 10:35:02 volumio nmbd[913]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.31.152 for name WORKGROUP<1d>. Oct 12 10:35:02 volumio nmbd[913]: This response was from IP 192.168.31.238, reporting an IP address of 192.168.31.238. Oct 12 10:35:02 volumio volumio[1109]: info: Loading i18n strings for locale zh Oct 12 10:35:02 volumio volumio[1109]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 12 10:35:02 volumio volumio[1109]: Updating browse sources language Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 10:35:02 volumio smbd[1455]: [2024/10/12 10:35:02.290352, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 12 10:35:02 volumio smbd[1455]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 12 10:35:02 volumio systemd[1]: Started Samba SMB Daemon. Oct 12 10:35:02 volumio systemd[1]: Reached target Multi-User System. Oct 12 10:35:02 volumio systemd[1]: Reached target Graphical Interface. Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::initPlayerControls Oct 12 10:35:02 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: Express server listening on port 3000 Oct 12 10:35:02 volumio volumio[1109]: [Metrics] WebUI: 3s 612.85ms Oct 12 10:35:02 volumio volumio[1109]: info: Setting Device type: x86 Oct 12 10:35:02 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 12 10:35:02 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 12 10:35:02 volumio systemd[1]: Startup finished in 8.745s (firmware) + 3.104s (loader) + 9.064s (kernel) + 18.766s (userspace) = 39.681s. Oct 12 10:35:02 volumio volumio[1109]: info: CoreStateMachine::resetVolumioState Oct 12 10:35:02 volumio volumio[1109]: info: CoreStateMachine::getcurrentVolume Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 12 10:35:02 volumio volumio[1109]: info: Completed loading Core Plugins Oct 12 10:35:02 volumio volumio[1109]: info: Preparing to generate the ALSA configuration file Oct 12 10:35:02 volumio volumio[1109]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 12 10:35:02 volumio volumio[1109]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 12 10:35:02 volumio volumio[1109]: info: Reading ALSA contributions from plugins. Oct 12 10:35:02 volumio volumio-remote-updater[801]: [2024-10-12 10:35:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728700498 101 Oct 12 10:35:02 volumio volumio[1109]: 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: 1 Oct 12 10:35:02 volumio volumio[1109]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Oct 12 10:35:02 volumio volumio[1109]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 12 10:35:02 volumio volumio[1109]: info: Reloading queue from file Oct 12 10:35:02 volumio volumio[1109]: info: VolumeController:: Volume=undefined Mute =false Oct 12 10:35:02 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 12 10:35:02 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 12 10:35:02 volumio volumio[1109]: info: CoreStateMachine::updateTrackBlock Oct 12 10:35:02 volumio volumio[1109]: info: CorePlayQueue::getTrackBlock Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 10:35:02 volumio volumio[1109]: info: CoreStateMachine::setRepeat null single undefined Oct 12 10:35:02 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 12 10:35:02 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 12 10:35:02 volumio volumio[1109]: info: CoreStateMachine::setRandom null Oct 12 10:35:02 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 12 10:35:02 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 12 10:35:02 volumio volumio[1109]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Oct 12 10:35:02 volumio volumio[1109]: info: VolumeController:: Volume=undefined Mute =false Oct 12 10:35:02 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 12 10:35:02 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 12 10:35:02 volumio volumio[1109]: info: Asound.conf file unchanged, so no further update is needed Oct 12 10:35:02 volumio volumio[1109]: info: Output device has changed, restarting MPD Oct 12 10:35:02 volumio sudo[1499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 10:35:02 volumio volumio[1109]: info: Output device has changed, restarting Shairport Sync Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:02 volumio sudo[1499]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:02 volumio sudo[1501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 10:35:02 volumio sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:02 volumio systemd[1]: Listening on mpd.socket. Oct 12 10:35:02 volumio volumio[1109]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 10:35:02 volumio volumio[1109]: info: ___________ START PLUGINS ___________ Oct 12 10:35:02 volumio volumio[1109]: info: ControllerMpd::onStart: Initializing MPD Oct 12 10:35:02 volumio volumio[1109]: info: Creating MPD Configuration file Oct 12 10:35:02 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:02 volumio systemd[1]: Starting Music Player Daemon... Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 10:35:02 volumio volumio[1109]: info: [1728700502539] CoreMusicLibrary::Adding element 媒体服务器 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 10:35:02 volumio sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 10:35:02 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:02 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:02 volumio sudo[1511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 10:35:02 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 12 10:35:02 volumio systemd[1]: mpd.service: Succeeded. Oct 12 10:35:02 volumio systemd[1]: Stopped Music Player Daemon. Oct 12 10:35:02 volumio systemd[1]: Starting Music Player Daemon... Oct 12 10:35:02 volumio volumio[1109]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 10:35:02 volumio volumio[1109]: info: [1728700502578] CoreMusicLibrary::Adding element Last_100 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 10:35:02 volumio volumio[1109]: info: [1728700502579] CoreMusicLibrary::Adding element Webradio Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 10:35:02 volumio volumio[1109]: info: Initializing BBC Radios Oct 12 10:35:02 volumio sudo[1515]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 10:35:02 volumio sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:02 volumio volumio[1109]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio sudo[1515]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 12 10:35:02 volumio sudo[1515]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:35:02 volumio volumio[1109]: info: Discovery: Getting this device information Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:02 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 10:35:02 volumio volumio[1109]: info: [1728700502614] CoreMusicLibrary::Adding element Jellyfin Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 10:35:02 volumio volumio[1109]: Cannot find translation for source Jellyfin Oct 12 10:35:02 volumio volumio[1109]: info: [jellyfin] Initialized plugin with device info: {"id":"6576be8c-9ca3-4f6e-ad09-3e064e726785","host":"http://192.168.31.152","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"","mute":false,"artist":"赵鹏","track":"乌兰巴托的夜晚","albumart":"http://192.168.31.152/albumart?cacheid=609&web=%E8%B5%B5%E9%B9%8F/%E4%B8%AD%E5%9B%BD%E6%9C%80%E5%A5%BD%E5%A3%B0%E9%9F%B3%3A%E4%BA%BA%E5%A3%B0%E6%B5%8B%E8%AF%95%E4%B8%93%E7%94%A8%E5%A4%A7%E7%A2%9F/extralarge&path=%2FUSB&metadata=false"}} Oct 12 10:35:02 volumio volumio[1109]: info: AutoStart - onStart Oct 12 10:35:02 volumio volumio[1109]: info: Loading i18n strings for locale zh Oct 12 10:35:02 volumio volumio[1109]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 12 10:35:02 volumio volumio[1109]: info: /tmp/myfifo created Oct 12 10:35:02 volumio volumio[1109]: info: /tmp/myfifosa created Oct 12 10:35:02 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Oct 12 10:35:02 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:02 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:02 volumio volumio[1109]: info: snd-dummy loaded Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 10:35:02 volumio volumio[1109]: info: Loading i18n strings for locale zh Oct 12 10:35:02 volumio volumio[1109]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 12 10:35:02 volumio sudo[1558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 12 10:35:02 volumio sudo[1558]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:02 volumio volumio[1109]: info: Volumio Calling Home Oct 12 10:35:02 volumio systemd[1]: Started RoonBridge. Oct 12 10:35:02 volumio sudo[1558]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:02 volumio volumio[1109]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785 Oct 12 10:35:02 volumio volumio[1109]: info: Discovery: Found device Volumio Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:02 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:02 volumio volumio[1109]: info: Discovery: this is already registered, 6576be8c-9ca3-4f6e-ad09-3e064e726785 Oct 12 10:35:02 volumio volumio[1109]: info: Discovery: Found device Volumio Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:02 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:02 volumio volumio[1109]: info: MPD Permissions set Oct 12 10:35:02 volumio volumio[1109]: info: MPD Permissions set Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:02 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:02 volumio volumio[1109]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 10:35:02 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:02 volumio volumio[1109]: info: minidlna: Found miniDLNA version 1.2.1 Oct 12 10:35:02 volumio volumio[1109]: info: minidlna: Starting minidlna.service Oct 12 10:35:02 volumio sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 12 10:35:02 volumio sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:02 volumio volumio[1109]: info: Starting Shairport Sync Oct 12 10:35:02 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Oct 12 10:35:02 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:03 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 12 10:35:03 volumio volumio[1109]: info: Starting Shairport Sync Oct 12 10:35:02 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:03 volumio sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 10:35:03 volumio sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:03 volumio volumio[1109]: info: Starting Shairport Sync Oct 12 10:35:03 volumio sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 10:35:03 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:03 volumio volumio[1109]: info: Starting Shairport Sync Oct 12 10:35:03 volumio sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 10:35:03 volumio sudo[1588]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 12 10:35:03 volumio sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:03 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 12 10:35:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Oct 12 10:35:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 12 10:35:03 volumio sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 10:35:03 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:03 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 12 10:35:03 volumio sudo[1596]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:03 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 12 10:35:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 12 10:35:03 volumio sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 12 10:35:03 volumio sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:03 volumio volumio[1109]: info: Shairport-Sync Started Oct 12 10:35:03 volumio volumio[1109]: Error adding Membership: Error: addMembership EINVAL Oct 12 10:35:03 volumio volumio[1109]: info: minidlna: systemctl start minidlna.service succeeded. Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:03 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:03 volumio sudo[1614]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 12 10:35:03 volumio volumio[1109]: info: MPD Permissions set Oct 12 10:35:03 volumio volumio[1109]: info: Shairport-Sync Started Oct 12 10:35:03 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:03 volumio volumio[1109]: info: Shairport-Sync Started Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 12 10:35:03 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 12 10:35:03 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 12 10:35:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 12 10:35:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 12 10:35:03 volumio sudo[1604]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 10:35:03 volumio volumio[1109]: info: Shairport-Sync Started Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.003 Warn: get lock file path: /tmp/.rnbgem0- Oct 12 10:35:03 volumio volumio[1109]: info: Volumio called home Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.207 Trace: [childprocess] using unix child process Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.253 Debug: PathForResource, filename: ../.update Oct 12 10:35:03 volumio mpd[1525]: Oct 12 10:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.278 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 12 10:35:03 volumio systemd[1]: Started Music Player Daemon. Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.282 Debug: PathForResource, filename: RAATServer Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.282 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.282 Debug: PathForResource, filename: RoonBridgeHelper Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.282 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 12 10:35:03 volumio sudo[1501]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:03 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:03 volumio RoonBridge[1564]: Initializing Oct 12 10:35:03 volumio volumio[1109]: info: Completed starting Core Plugins Oct 12 10:35:03 volumio volumio[1109]: info: ------------------------------------------- Oct 12 10:35:03 volumio volumio[1109]: info: ----- MyVolumio plugins startup ---- Oct 12 10:35:03 volumio volumio[1109]: info: ------------------------------------------- Oct 12 10:35:03 volumio volumio[1109]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.320 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 12 10:35:03 volumio RoonBridge[1564]: 00:00:00.347 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 12 10:35:03 volumio volumio[1109]: error: MPD error: The expression evaluated to a falsy value: Oct 12 10:35:03 volumio volumio[1109]: assert.ok(self.idling) Oct 12 10:35:03 volumio volumio[1109]: error: The expression evaluated to a falsy value: Oct 12 10:35:03 volumio volumio[1109]: assert.ok(self.idling) Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 12 10:35:03 volumio volumio[1109]: info: MPD running with PID1525 Oct 12 10:35:03 volumio volumio[1109]: ,establishing connection Oct 12 10:35:03 volumio RoonBridge[1564]: Not Running (.o) Oct 12 10:35:03 volumio volumio[1109]: error: updateQueue error: null Oct 12 10:35:03 volumio volumio[1109]: error: updateQueue error: null Oct 12 10:35:03 volumio sudo[1649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 12 10:35:03 volumio sudo[1649]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:03 volumio systemd[1]: Stopping Music Player Daemon... Oct 12 10:35:03 volumio systemd[1]: mpd.service: Succeeded. Oct 12 10:35:03 volumio systemd[1]: Stopped Music Player Daemon. Oct 12 10:35:03 volumio systemd[1]: Starting Music Player Daemon... Oct 12 10:35:03 volumio RoonBridge[1564]: Running Oct 12 10:35:03 volumio sudo[1651]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 12 10:35:03 volumio sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:03 volumio sudo[1651]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:04 volumio volumio[1109]: 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 Oct 12 10:35:04 volumio RoonBridge[1564]: 00:00:00.057 Warn: get lock file path: /tmp/.rnbhgem0- Oct 12 10:35:04 volumio nmbd[913]: [2024/10/12 10:35:04.168600, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) Oct 12 10:35:04 volumio nmbd[913]: my_name_register_failed: Failed to register my name VOLUMIO<00> on subnet 192.168.31.152. Oct 12 10:35:04 volumio nmbd[913]: [2024/10/12 10:35:04.168717, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) Oct 12 10:35:04 volumio nmbd[913]: standard_fail_register: Failed to register/refresh name VOLUMIO<00> on subnet 192.168.31.152 Oct 12 10:35:04 volumio mpd[1659]: Oct 12 10:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 12 10:35:04 volumio systemd[1]: Started Music Player Daemon. Oct 12 10:35:04 volumio sudo[1649]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:04 volumio volumio[1109]: error: updateQueue error: null Oct 12 10:35:04 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 10:35:05 volumio volumio[1109]: 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: 5 Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::volumioGetVisibleSources Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:05 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 10:35:05 volumio volumio[1109]: info: Received Get System Info Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:35:05 volumio volumio[1109]: info: Discovery: Getting this device information Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:05 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:05 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:05 volumio volumio[1109]: info: Listing playlists Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 10:35:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 10:35:06 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:06 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:06 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:06 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:01 volumio ntpd[1098]: Soliciting pool server 84.16.67.12 Oct 12 10:35:01 volumio ntpd[1098]: Soliciting pool server 185.209.85.222 Oct 12 10:35:03 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 10:35:07 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 10:35:11 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 10:35:15 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 10:35:16 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:35:16 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:35:16 volumio volumio[1109]: info: Discovery: Getting this device information Oct 12 10:35:16 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:16 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:16 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:35:19 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 10:35:23 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 10:35:23 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 10:35:23 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 12 10:35:23 volumio sudo[2934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 12 10:35:23 volumio sudo[2934]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:23 volumio sudo[2940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 12 10:35:23 volumio sudo[2940]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:23 volumio sudo[2940]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:23 volumio sudo[2950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 12 10:35:23 volumio sudo[2950]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:23 volumio sudo[2950]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:23 volumio sudo[2958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 12 10:35:23 volumio sudo[2958]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:23 volumio sudo[2958]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:23 volumio sudo[2934]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:23 volumio sudo[2969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 10:35:23 volumio sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:23 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 12 10:35:23 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 12 10:35:23 volumio sudo[2969]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:23 volumio sudo[2973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 10:35:23 volumio sudo[2973]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:23 volumio sudo[2973]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:23 volumio sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 12 10:35:23 volumio sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 10:35:25 volumio sudo[2978]: pam_unix(sudo:session): session closed for user root Oct 12 10:35:27 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 10:35:27 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 12 10:35:27 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 12 10:35:27 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 12 10:35:27 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 12 10:35:27 volumio volumio[1109]: info: Received Get System Version Oct 12 10:35:27 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 10:35:46 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:35:46 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:35:46 volumio volumio[1109]: info: Discovery: Getting this device information Oct 12 10:35:46 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:35:46 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:35:46 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:36:16 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:36:16 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:36:16 volumio volumio[1109]: info: Discovery: Getting this device information Oct 12 10:36:16 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:36:16 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:36:16 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:36:27 volumio volumio[1109]: info: CoreCommandRouter::volumioGetQueue Oct 12 10:36:27 volumio volumio[1109]: info: CoreStateMachine::getQueue Oct 12 10:36:27 volumio volumio[1109]: info: CorePlayQueue::getQueue Oct 12 10:36:35 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 10:36:35 volumio volumio[1109]: info: CURURI: music-library Oct 12 10:36:35 volumio volumio[1109]: info: Preload queue cleared Oct 12 10:36:37 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 10:36:37 volumio volumio[1109]: info: CURURI: music-library/NAS Oct 12 10:36:37 volumio volumio[1109]: info: Preload queue cleared Oct 12 10:36:37 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 10:36:37 volumio volumio[1109]: info: CURURI: music-library/NAS/音乐 Oct 12 10:36:38 volumio volumio[1109]: info: Preload queue cleared Oct 12 10:36:38 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 10:36:38 volumio volumio[1109]: info: CURURI: music-library/NAS/音乐/DSD Oct 12 10:36:39 volumio volumio[1109]: info: Preload queue cleared Oct 12 10:36:40 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 12 10:36:40 volumio volumio[1109]: info: CURURI: music-library/NAS/音乐/DSD/DSD Oct 12 10:36:40 volumio volumio[1109]: info: Preload queue cleared Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 12 10:36:44 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 10:36:46 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 10:36:46 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 10:36:46 volumio volumio[1109]: info: Discovery: Getting this device information Oct 12 10:36:46 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:36:46 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:36:46 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 10:36:47 volumio volumio[1109]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 12 10:36:47 volumio volumio[1109]: info: Preparing to save Alsa Options, stopping services first Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Oct 12 10:36:47 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::volumioPause Oct 12 10:36:47 volumio volumio[1109]: info: CoreStateMachine::pause Oct 12 10:36:47 volumio volumio[1109]: info: CoreStateMachine::stPlaybackTimer Oct 12 10:36:47 volumio volumio[1109]: info: CoreStateMachine::servicePause Oct 12 10:36:47 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::servicePause Oct 12 10:36:47 volumio volumio[1109]: info: ControllerMpd::pause Oct 12 10:36:47 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand pause Oct 12 10:36:47 volumio volumio[1109]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL USB AUDIO"}} Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 10:36:47 volumio volumio[1109]: info: Device 5 does not have any Mixer Control Available Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 12 10:36:47 volumio volumio[1109]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 12 10:36:47 volumio volumio[1109]: info: Disabling external Volume Control Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 10:36:47 volumio volumio[1109]: info: CoreStateMachine::pushState Oct 12 10:36:47 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 10:36:47 volumio volumio[1109]: info: CoreCommandRouter::volumioPushState Oct 12 10:36:47 volumio volumio[1109]: info: Preparing to generate the ALSA configuration file Oct 12 10:36:47 volumio volumio[1109]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 12 10:36:47 volumio volumio[1109]: info: Reading ALSA contributions from plugins. Oct 12 10:36:47 volumio volumio[1109]: info: Oct 12 10:36:47 volumio volumio[1109]: ---------------------------- MPD announces state update: player Oct 12 10:36:47 volumio volumio[1109]: info: sendMpdCommand pause took 79 milliseconds Oct 12 10:36:47 volumio volumio[1109]: info: ControllerMpd::getState Oct 12 10:36:47 volumio volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Oct 12 10:36:47 volumio volumio[1109]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 10:36:47 volumio volumio[1109]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 12 10:36:47 volumio volumio[1109]: at Object.readdirSync (fs.js:1048:3) Oct 12 10:36:47 volumio volumio[1109]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 12 10:36:47 volumio volumio[1109]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 12 10:36:47 volumio volumio[1109]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 12 10:36:47 volumio volumio[1109]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 12 10:36:47 volumio volumio[1109]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 12 10:36:47 volumio volumio[1109]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 12 10:36:47 volumio volumio[1109]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 12 10:36:47 volumio volumio[1109]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 12 10:36:47 volumio volumio[1109]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 12 10:36:47 volumio volumio[1109]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 12 10:36:47 volumio volumio[1109]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 12 10:36:47 volumio volumio[1109]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 12 10:36:47 volumio volumio[1109]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 12 10:36:47 volumio volumio[1109]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 12 10:36:47 volumio volumio[1109]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 12 10:36:47 volumio volumio[1109]: errno: -20, Oct 12 10:36:47 volumio volumio[1109]: syscall: 'scandir', Oct 12 10:36:47 volumio volumio[1109]: code: 'ENOTDIR', Oct 12 10:36:47 volumio volumio[1109]: path: '/tmp/peppyrunning' Oct 12 10:36:47 volumio volumio[1109]: } Oct 12 10:36:47 volumio volumio[1109]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 10:36:47 volumio sudo[3233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-12 10:35 Oct 12 10:36:47 volumio sudo[3233]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"