-- Logs begin at Thu 2024-10-17 18:26:44 CST, end at Thu 2024-10-17 18:28:55 CST. -- Oct 17 18:27:00 volumio volumio[907]: info: Loading plugin "network"... Oct 17 18:27:00 volumio volumio[907]: info: Refreshing Cached IP Addresses Oct 17 18:27:00 volumio volumio[907]: info: Loading plugin "services"... Oct 17 18:27:00 volumio volumio[907]: info: Loading plugin "alsa_controller"... Oct 17 18:27:00 volumio sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 18:27:00 volumio sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 18:27:00 volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 18:27:00 volumio sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:00 volumio sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:00 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:00 volumio sudo[1199]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:00 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:00 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:00 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 18:27:00 volumio volumio[907]: info: Loading plugin "wizard"... Oct 17 18:27:00 volumio volumio[907]: info: Loading plugin "networkfs"... Oct 17 18:27:00 volumio volumio[907]: info: Starting Udev Watcher for removable devices Oct 17 18:27:00 volumio volumio[907]: info: Ignoring mount for partition: boot Oct 17 18:27:00 volumio volumio[907]: info: Ignoring mount for partition: volumio Oct 17 18:27:00 volumio volumio[907]: info: Ignoring mount for partition: volumio_data Oct 17 18:27:00 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 18:27:00 volumio volumio[907]: info: Loading plugin "volumio_command_line_client"... Oct 17 18:27:00 volumio volumio[907]: info: Loading plugin "upnp"... Oct 17 18:27:01 volumio volumio[907]: info: [1729160821001] Starting Upmpd Daemon Oct 17 18:27:01 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 18:27:01 volumio volumio[907]: info: Loading plugin "my_music"... Oct 17 18:27:01 volumio volumio[907]: info: Loading plugin "mpd"... Oct 17 18:27:01 volumio volumio[907]: info: Loading plugin "upnp_browser"... Oct 17 18:27:02 volumio volumio[907]: info: Loading plugin "alarm-clock"... Oct 17 18:27:02 volumio volumio[907]: info: Loading plugin "airplay_emulation"... Oct 17 18:27:02 volumio volumio[907]: info: Starting Shairport Sync Oct 17 18:27:02 volumio volumio[907]: info: Loading plugin "last_100"... Oct 17 18:27:02 volumio volumio[907]: info: Loading plugin "webradio"... Oct 17 18:27:02 volumio dbus-daemon[653]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.6' (uid=0 pid=1168 comm="/usr/lib/chromium/chromium --show-component-extens") Oct 17 18:27:02 volumio volumio[907]: info: Loading plugin "i2s_dacs"... Oct 17 18:27:02 volumio systemd[1]: Starting Bluetooth service... Oct 17 18:27:02 volumio volumio[907]: info: I2S DAC not set, start Auto-detection Oct 17 18:27:02 volumio volumio[907]: info: Loading plugin "volumiodiscovery"... Oct 17 18:27:02 volumio dhcpcd[752]: eth0: leased 192.168.10.105 for 14400 seconds Oct 17 18:27:02 volumio avahi-daemon[640]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.10.105. Oct 17 18:27:02 volumio avahi-daemon[640]: New relevant interface eth0.IPv4 for mDNS. Oct 17 18:27:02 volumio avahi-daemon[640]: Registering new address record for 192.168.10.105 on eth0.IPv4. Oct 17 18:27:02 volumio dhcpcd[752]: eth0: adding route to 192.168.10.0/24 Oct 17 18:27:02 volumio dhcpcd[752]: eth0: adding default route via 192.168.10.1 Oct 17 18:27:02 volumio bluetoothd[1228]: Bluetooth daemon 5.50 Oct 17 18:27:02 volumio bluetoothd[1228]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 17 18:27:02 volumio dbus-daemon[653]: [system] Successfully activated service 'org.bluez' Oct 17 18:27:02 volumio systemd[1]: Started Bluetooth service. Oct 17 18:27:02 volumio bluetoothd[1228]: Starting SDP server Oct 17 18:27:02 volumio bluetoothd[1228]: Excluding (cli) sap Oct 17 18:27:02 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 17 18:27:02 volumio kernel: Bluetooth: BNEP filters: protocol multicast Oct 17 18:27:02 volumio kernel: Bluetooth: BNEP socket layer initialized Oct 17 18:27:02 volumio bluetoothd[1228]: Bluetooth management interface 1.22 initialized Oct 17 18:27:02 volumio dbus-daemon[653]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.7' (uid=0 pid=1228 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 17 18:27:02 volumio volumio[907]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 18:27:02 volumio volumio[907]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 18:27:02 volumio volumio[907]: *** WARNING *** For more information see Oct 17 18:27:02 volumio volumio[907]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 18:27:02 volumio node[907]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 18:27:02 volumio volumio[907]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 18:27:02 volumio volumio[907]: *** WARNING *** For more information see Oct 17 18:27:02 volumio node[907]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 18:27:02 volumio node[907]: *** WARNING *** For more information see Oct 17 18:27:02 volumio node[907]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 18:27:02 volumio node[907]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 18:27:02 volumio node[907]: *** WARNING *** For more information see Oct 17 18:27:02 volumio volumio[907]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 18:27:02 volumio volumio[907]: info: Discovery: Started advertising with name: Volumio Oct 17 18:27:02 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 18:27:02 volumio volumio[907]: info: Loading plugin "jellyfin"... Oct 17 18:27:02 volumio systemd[1]: Starting Hostname Service... Oct 17 18:27:03 volumio ntpd[843]: ntpd exiting on signal 15 (Terminated) Oct 17 18:27:03 volumio systemd[1]: Stopping Network Time Service... Oct 17 18:27:03 volumio systemd[1]: ntp.service: Succeeded. Oct 17 18:27:03 volumio systemd[1]: Stopped Network Time Service. Oct 17 18:27:03 volumio systemd[1]: Starting Network Time Service... Oct 17 18:27:03 volumio ntpd[1282]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 17 18:27:03 volumio ntpd[1282]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 17 18:27:03 volumio systemd[1]: Started Network Time Service. Oct 17 18:27:03 volumio ntpd[1289]: proto: precision = 0.252 usec (-22) Oct 17 18:27:03 volumio ntpd[1289]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 17 18:27:03 volumio ntpd[1289]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 17 18:27:03 volumio ntpd[1289]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 660 days ago Oct 17 18:27:03 volumio ntpd[1289]: Listen and drop on 0 v6wildcard [::]:123 Oct 17 18:27:03 volumio ntpd[1289]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 17 18:27:03 volumio ntpd[1289]: Listen normally on 2 lo 127.0.0.1:123 Oct 17 18:27:03 volumio ntpd[1289]: Listen normally on 3 eth0 192.168.10.105:123 Oct 17 18:27:03 volumio ntpd[1289]: Listening on routing socket on fd #20 for interface updates Oct 17 18:27:03 volumio ntpd[1289]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 17 18:27:03 volumio ntpd[1289]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 17 18:27:03 volumio dbus-daemon[653]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 17 18:27:03 volumio systemd[1]: Started Hostname Service. Oct 17 18:27:04 volumio ntpd[1289]: Soliciting pool server 114.33.15.129 Oct 17 18:27:04 volumio volumio[907]: info: Loading plugin "jellyfin_server"... Oct 17 18:27:05 volumio volumio[907]: info: Loading plugin "autostart"... Oct 17 18:27:05 volumio ntpd[1289]: Soliciting pool server 183.177.72.201 Oct 17 18:27:05 volumio ntpd[1289]: Soliciting pool server 118.163.81.63 Oct 17 18:27:05 volumio volumio[907]: info: Applying required configuration parameters for plugin autostart Oct 17 18:27:05 volumio volumio[907]: info: AutoStart - onVolumioStart - read config.json Oct 17 18:27:05 volumio volumio[907]: info: Loading plugin "peppy_screensaver"... Oct 17 18:27:06 volumio volumio[907]: info: Applying required configuration parameters for plugin peppy_screensaver Oct 17 18:27:06 volumio volumio[907]: info: Loading plugin "outputs"... Oct 17 18:27:06 volumio volumio[907]: info: Loading plugin "albumart"... Oct 17 18:27:06 volumio volumio[907]: info: Plugin example_plugin is not enabled Oct 17 18:27:06 volumio volumio[907]: info: Loading plugin "inputs"... Oct 17 18:27:06 volumio volumio[907]: info: Loading plugin "updater_comm"... Oct 17 18:27:06 volumio ntpd[1289]: Soliciting pool server 122.117.253.246 Oct 17 18:27:06 volumio ntpd[1289]: Soliciting pool server 210.243.152.152 Oct 17 18:27:06 volumio volumio[907]: Forking 1 albumart workers Oct 17 18:27:06 volumio volumio[907]: info: Plugin mpdemulation is not enabled Oct 17 18:27:06 volumio volumio[907]: info: Loading plugin "rest_api"... Oct 17 18:27:06 volumio volumio[907]: info: Loading plugin "websocket"... Oct 17 18:27:06 volumio volumio[907]: info: Starting Socket.io Server version 2.3.0 Oct 17 18:27:06 volumio volumio[907]: info: Loading plugin "RoonBridge"... Oct 17 18:27:06 volumio ntpd[1289]: Soliciting pool server 123.204.232.128 Oct 17 18:27:06 volumio nmbd[834]: [2024/10/17 18:27:06.648233, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 17 18:27:06 volumio nmbd[834]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 17 18:27:06 volumio systemd[1]: Started Samba NMB Daemon. Oct 17 18:27:06 volumio systemd[1]: Starting Samba Winbind Daemon... Oct 17 18:27:06 volumio volumio[907]: info: Applying required configuration parameters for plugin RoonBridge Oct 17 18:27:06 volumio volumio[907]: info: Loading plugin "lms"... Oct 17 18:27:06 volumio winbindd[1355]: [2024/10/17 18:27:06.987343, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 17 18:27:06 volumio winbindd[1355]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 17 18:27:07 volumio winbindd[1355]: [2024/10/17 18:27:07.006447, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 17 18:27:07 volumio systemd[1]: Started Samba Winbind Daemon. Oct 17 18:27:07 volumio winbindd[1355]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 17 18:27:07 volumio systemd[1]: Starting Samba SMB Daemon... Oct 17 18:27:07 volumio volumio[907]: Starting albumart workers Oct 17 18:27:07 volumio ntpd[1289]: Soliciting pool server 125.229.106.76 Oct 17 18:27:07 volumio ntpd[1289]: Soliciting pool server 114.34.171.136 Oct 17 18:27:07 volumio ntpd[1289]: Soliciting pool server 17.253.116.253 Oct 17 18:27:07 volumio ntpd[1289]: Soliciting pool server 218.35.163.245 Oct 17 18:27:07 volumio volumio[907]: info: Loading plugin "minidlna"... Oct 17 18:27:07 volumio volumio[907]: info: Applying required configuration parameters for plugin minidlna Oct 17 18:27:07 volumio smbd[1360]: [2024/10/17 18:27:07.594648, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 17 18:27:07 volumio systemd[1]: Started Samba SMB Daemon. Oct 17 18:27:07 volumio systemd[1]: Reached target Multi-User System. Oct 17 18:27:07 volumio smbd[1360]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 17 18:27:07 volumio systemd[1]: Reached target Graphical Interface. Oct 17 18:27:07 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 17 18:27:07 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 17 18:27:07 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 17 18:27:07 volumio systemd[1]: Startup finished in 3.805s (firmware) + 2.829s (loader) + 22.534s (kernel) + 23.859s (userspace) = 53.028s. Oct 17 18:27:07 volumio volumio[907]: info: Loading i18n strings for locale zh Oct 17 18:27:07 volumio volumio[907]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 17 18:27:07 volumio volumio[907]: Updating browse sources language Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 18:27:07 volumio volumio-remote-updater[661]: [2024-10-17 18:27:07] [connect] Successful connection Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::initPlayerControls Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 18:27:07 volumio volumio[907]: Express server listening on port 3000 Oct 17 18:27:07 volumio volumio[907]: [Metrics] WebUI: 13s 401.96ms Oct 17 18:27:07 volumio volumio[907]: info: Setting Device type: x86 Oct 17 18:27:07 volumio volumio[907]: info: CoreStateMachine::resetVolumioState Oct 17 18:27:07 volumio volumio[907]: info: CoreStateMachine::getcurrentVolume Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 18:27:07 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:08 volumio volumio[907]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 17 18:27:08 volumio volumio[907]: info: Completed loading Core Plugins Oct 17 18:27:08 volumio volumio[907]: info: Preparing to generate the ALSA configuration file Oct 17 18:27:08 volumio volumio[907]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 17 18:27:08 volumio volumio[907]: wlan0 Interface doesn't support scanning. Oct 17 18:27:08 volumio volumio[907]: info: Cannot use regular scanning, forcing with ap-force Oct 17 18:27:08 volumio sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 17 18:27:08 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:08 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:08 volumio volumio[907]: command failed: No such device (-19) Oct 17 18:27:08 volumio volumio[907]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 17 18:27:08 volumio volumio[907]: command failed: No such device (-19) Oct 17 18:27:08 volumio volumio[907]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 17 18:27:08 volumio volumio[907]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 17 18:27:08 volumio volumio[907]: info: Reading ALSA contributions from plugins. Oct 17 18:27:08 volumio ntpd[1289]: Soliciting pool server 220.134.230.37 Oct 17 18:27:08 volumio ntpd[1289]: Soliciting pool server 103.159.118.4 Oct 17 18:27:08 volumio ntpd[1289]: Soliciting pool server 114.35.131.27 Oct 17 18:27:08 volumio volumio-remote-updater[661]: [2024-10-17 18:27:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729160827 101 Oct 17 18:27:08 volumio volumio[907]: 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 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:08 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:08 volumio volumio[907]: 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 17 18:27:08 volumio volumio[907]: info: Reloading queue from file Oct 17 18:27:08 volumio volumio[907]: info: VolumeController:: Volume=39 Mute =false Oct 17 18:27:08 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:08 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:08 volumio volumio[907]: info: CoreStateMachine::updateTrackBlock Oct 17 18:27:08 volumio volumio[907]: info: CorePlayQueue::getTrackBlock Oct 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 18:27:08 volumio volumio[907]: info: CoreStateMachine::setRepeat null single undefined Oct 17 18:27:08 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:08 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:08 volumio volumio[907]: info: CoreStateMachine::setRandom null Oct 17 18:27:08 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:08 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:08 volumio volumio[907]: info: VolumeController:: Volume=39 Mute =false Oct 17 18:27:08 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:08 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:08 volumio volumio[907]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785 Oct 17 18:27:08 volumio volumio[907]: info: Discovery: Found device Volumio Oct 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:08 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:08 volumio volumio[907]: info: Asound.conf file unchanged, so no further update is needed Oct 17 18:27:08 volumio volumio[907]: info: Output device has changed, restarting MPD Oct 17 18:27:08 volumio sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 18:27:08 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:08 volumio volumio[907]: info: Output device has changed, restarting Shairport Sync Oct 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:08 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:08 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:08 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 18:27:08 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:08 volumio systemd[1]: Listening on mpd.socket. Oct 17 18:27:08 volumio systemd[1]: Starting Music Player Daemon... Oct 17 18:27:08 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 18:27:08 volumio volumio[907]: info: ___________ START PLUGINS ___________ Oct 17 18:27:09 volumio volumio[907]: info: ControllerMpd::onStart: Initializing MPD Oct 17 18:27:09 volumio volumio[907]: info: Creating MPD Configuration file Oct 17 18:27:09 volumio sudo[1404]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 18:27:09 volumio sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 18:27:09 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:09 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:09 volumio sudo[1404]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 17 18:27:09 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:09 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 18:27:09 volumio volumio[907]: info: [1729160829118] CoreMusicLibrary::Adding element 媒体服务器 Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 18:27:09 volumio sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 18:27:09 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:09 volumio ntpd[1289]: Soliciting pool server 103.147.22.149 Oct 17 18:27:09 volumio ntpd[1289]: Soliciting pool server 112.104.189.124 Oct 17 18:27:09 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 17 18:27:09 volumio systemd[1]: mpd.service: Succeeded. Oct 17 18:27:09 volumio systemd[1]: Stopped Music Player Daemon. Oct 17 18:27:09 volumio systemd[1]: Starting Music Player Daemon... Oct 17 18:27:09 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:09 volumio sudo[1416]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 18:27:09 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:09 volumio sudo[1416]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 17 18:27:09 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:09 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 18:27:09 volumio volumio[907]: info: [1729160829394] CoreMusicLibrary::Adding element Last_100 Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 18:27:09 volumio volumio[907]: info: [1729160829400] CoreMusicLibrary::Adding element Webradio Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 18:27:09 volumio volumio[907]: info: Initializing BBC Radios Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:27:09 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:09 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 18:27:09 volumio volumio[907]: info: [1729160829581] CoreMusicLibrary::Adding element Jellyfin Oct 17 18:27:09 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 18:27:09 volumio volumio[907]: Cannot find translation for source Jellyfin Oct 17 18:27:09 volumio volumio[907]: info: [jellyfin] Initialized plugin with device info: {"id":"6576be8c-9ca3-4f6e-ad09-3e064e726785","host":"http://127.0.0.1","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":39,"mute":false,"artist":"赵鹏","track":"乌兰巴托的夜晚","albumart":"http://127.0.0.1/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 17 18:27:09 volumio volumio[907]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true Oct 17 18:27:09 volumio volumio[907]: info: AutoStart - onStart Oct 17 18:27:09 volumio volumio[907]: info: Loading i18n strings for locale zh Oct 17 18:27:09 volumio volumio[907]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 17 18:27:09 volumio sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin Oct 17 18:27:09 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:09 volumio volumio[907]: info: /tmp/myfifo created Oct 17 18:27:09 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 17 18:27:09 volumio volumio[907]: info: /tmp/myfifosa created Oct 17 18:27:09 volumio systemd[1]: Starting Jellyfin... Oct 17 18:27:09 volumio systemd[1]: Started Jellyfin. Oct 17 18:27:09 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:09 volumio sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Oct 17 18:27:09 volumio sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:09 volumio sudo[1441]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:09 volumio volumio[907]: info: snd-dummy loaded Oct 17 18:27:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Oct 17 18:27:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:10 volumio ntpd[1289]: Soliciting pool server 118.163.81.61 Oct 17 18:27:10 volumio ntpd[1289]: Soliciting pool server 2001:b031:5c02:ff00::5 Oct 17 18:27:10 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 18:27:10 volumio volumio[907]: info: Loading i18n strings for locale zh Oct 17 18:27:10 volumio volumio[907]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 17 18:27:10 volumio sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 17 18:27:10 volumio sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:10 volumio volumio[907]: info: Volumio Calling Home Oct 17 18:27:10 volumio systemd[1]: Started RoonBridge. Oct 17 18:27:10 volumio sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Oct 17 18:27:10 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:10 volumio sudo[1467]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:10 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 17 18:27:10 volumio systemd[1]: Starting Logitech Media Server Daemon... Oct 17 18:27:10 volumio systemd[1]: Started Logitech Media Server Daemon. Oct 17 18:27:10 volumio systemd[1489]: logitechmediaserver.service: Failed to execute command: No such file or directory Oct 17 18:27:10 volumio systemd[1489]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Oct 17 18:27:10 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Oct 17 18:27:10 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Oct 17 18:27:10 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:11 volumio sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 18:27:11 volumio sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:11 volumio sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 18:27:11 volumio sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:11 volumio sudo[1492]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:11 volumio volumio[907]: info: logitechmediaserver started Oct 17 18:27:11 volumio volumio[907]: info: MPD Permissions set Oct 17 18:27:11 volumio volumio[907]: info: MPD Permissions set Oct 17 18:27:11 volumio sudo[1494]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:12 volumio sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Oct 17 18:27:12 volumio sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:12 volumio sudo[1503]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:12 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:12 volumio volumio[907]: 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 17 18:27:12 volumio volumio[907]: info: Volumio called home Oct 17 18:27:12 volumio volumio[907]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:12 volumio sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 18:27:12 volumio sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:12 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 18:27:12 volumio sudo[1507]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:12 volumio RoonBridge[1476]: 00:00:00.039 Warn: get lock file path: /tmp/.rnbgem0- Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 18:27:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:13 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Oct 17 18:27:13 volumio volumio[907]: info: minidlna: Found miniDLNA version 1.2.1 Oct 17 18:27:13 volumio volumio[907]: info: minidlna: Starting minidlna.service Oct 17 18:27:13 volumio sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 17 18:27:13 volumio sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:13 volumio sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 18:27:13 volumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:13 volumio mpd[1421]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 18:27:13 volumio mpd[1421]: output: No 'audio_output' defined in config file Oct 17 18:27:13 volumio sudo[1522]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:13 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 17 18:27:13 volumio sudo[1524]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:13 volumio mpd[1421]: output: Successfully detected a sndio audio device Oct 17 18:27:13 volumio mpd[1421]: zeroconf: No global port, disabling zeroconf Oct 17 18:27:13 volumio systemd[1]: Started Music Player Daemon. Oct 17 18:27:13 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:13 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:13 volumio volumio[907]: info: MPD Permissions set Oct 17 18:27:13 volumio volumio[907]: info: Starting Shairport Sync Oct 17 18:27:13 volumio RoonBridge[1476]: 00:00:01.039 Trace: [childprocess] using unix child process Oct 17 18:27:13 volumio volumio[907]: info: Starting Shairport Sync Oct 17 18:27:13 volumio volumio[907]: info: Starting Shairport Sync Oct 17 18:27:13 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 18:27:13 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:13 volumio RoonBridge[1476]: 00:00:01.206 Debug: PathForResource, filename: ../.update Oct 17 18:27:13 volumio RoonBridge[1476]: 00:00:01.274 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 17 18:27:13 volumio RoonBridge[1476]: 00:00:01.288 Debug: PathForResource, filename: RAATServer Oct 17 18:27:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 18:27:13 volumio RoonBridge[1476]: 00:00:01.298 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 17 18:27:13 volumio RoonBridge[1476]: 00:00:01.299 Debug: PathForResource, filename: RoonBridgeHelper Oct 17 18:27:13 volumio RoonBridge[1476]: 00:00:01.299 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 17 18:27:13 volumio volumio[907]: info: Starting Shairport Sync Oct 17 18:27:13 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 18:27:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 18:27:13 volumio sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 18:27:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 18:27:13 volumio RoonBridge[1476]: Initializing Oct 17 18:27:13 volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:13 volumio sudo[1531]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:13 volumio RoonBridge[1476]: 00:00:01.459 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 17 18:27:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 18:27:13 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 18:27:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 18:27:13 volumio sudo[1537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 18:27:13 volumio sudo[1537]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:13 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:13 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 18:27:13 volumio sudo[1534]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:14 volumio RoonBridge[1476]: 00:00:01.606 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 17 18:27:14 volumio sudo[1545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 18:27:14 volumio sudo[1545]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 18:27:14 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 18:27:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 18:27:14 volumio volumio[907]: info: minidlna: systemctl start minidlna.service succeeded. Oct 17 18:27:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 18:27:14 volumio sudo[1537]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 17 18:27:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 18:27:14 volumio volumio[907]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 18:27:14 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 18:27:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 18:27:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 18:27:14 volumio sudo[1545]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:14 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 18:27:14 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:14 volumio RoonBridge[1476]: Not Running (.o) Oct 17 18:27:14 volumio systemd[1]: Stopping Music Player Daemon... Oct 17 18:27:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:14 volumio sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 18:27:14 volumio sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:14 volumio systemd[1]: mpd.service: Succeeded. Oct 17 18:27:14 volumio systemd[1]: Stopped Music Player Daemon. Oct 17 18:27:14 volumio systemd[1]: Starting Music Player Daemon... Oct 17 18:27:14 volumio sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 17 18:27:14 volumio sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 18:27:14 volumio sudo[1572]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:14 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 17 18:27:14 volumio sudo[1578]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:14 volumio sudo[1579]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 18:27:14 volumio sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:14 volumio sudo[1579]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 17 18:27:14 volumio sudo[1579]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:15 volumio volumio[907]: info: MPD running with PID1421 Oct 17 18:27:15 volumio volumio[907]: ,establishing connection Oct 17 18:27:15 volumio volumio[907]: info: Shairport-Sync Started Oct 17 18:27:15 volumio volumio[907]: Error adding Membership: Error: addMembership EINVAL Oct 17 18:27:15 volumio volumio[907]: info: Shairport-Sync Started Oct 17 18:27:15 volumio volumio[907]: info: Shairport-Sync Started Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:14] [INF] [1] Main: Jellyfin version: 10.9.6 Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"] Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Arguments: ["/opt/jellyfin/jellyfin_10.9.6/jellyfin.dll", "-d", "/opt/jellyfin/data", "-C", "/opt/jellyfin/cache", "-c", "/opt/jellyfin/config", "-l", "/opt/jellyfin/log", "--ffmpeg", "/usr/lib/jellyfin-ffmpeg/ffmpeg"] Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Operating system: Debian GNU/Linux 10 (buster) Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Architecture: X64 Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: 64-Bit Process: True Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: User Interactive: True Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Processor count: 2 Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Program data path: /opt/jellyfin/data Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Log directory path: /opt/jellyfin/log Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Config directory path: /opt/jellyfin/config Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Cache path: /opt/jellyfin/cache Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web Oct 17 18:27:15 volumio jellyfin[1440]: [18:27:15] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/ Oct 17 18:27:15 volumio volumio[907]: error: MPD error: Error: write EPIPE Oct 17 18:27:15 volumio volumio[907]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Oct 17 18:27:15 volumio volumio[907]: error: MPD error: The expression evaluated to a falsy value: Oct 17 18:27:15 volumio volumio[907]: assert.ok(self.idling) Oct 17 18:27:15 volumio volumio[907]: error: The expression evaluated to a falsy value: Oct 17 18:27:15 volumio volumio[907]: assert.ok(self.idling) Oct 17 18:27:15 volumio RoonBridge[1476]: Running Oct 17 18:27:15 volumio volumio[907]: error: MPD error: Error: write EPIPE Oct 17 18:27:15 volumio volumio[907]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Oct 17 18:27:15 volumio volumio[907]: error: MPD error: The expression evaluated to a falsy value: Oct 17 18:27:15 volumio volumio[907]: assert.ok(self.idling) Oct 17 18:27:15 volumio volumio[907]: error: The expression evaluated to a falsy value: Oct 17 18:27:15 volumio volumio[907]: assert.ok(self.idling) Oct 17 18:27:15 volumio volumio[907]: info: Upmpdcli Daemon Started Oct 17 18:27:16 volumio volumio[907]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 18:27:16 volumio sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 18:27:16 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:16 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:17 volumio volumio[907]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 18:27:17 volumio sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 18:27:17 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:17 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:17 volumio jellyfin[1440]: [18:27:17] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["CreateNetworkConfiguration", "MigrateMusicBrainzTimeout", "MigrateNetworkConfiguration"] Oct 17 18:27:18 volumio volumio[907]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 18:27:18 volumio jellyfin[1440]: [18:27:18] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache Oct 17 18:27:18 volumio sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 18:27:18 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:18 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:18 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 17 18:27:18 volumio volumio[907]: info: Completed starting Core Plugins Oct 17 18:27:18 volumio volumio[907]: info: ------------------------------------------- Oct 17 18:27:18 volumio volumio[907]: info: ----- MyVolumio plugins startup ---- Oct 17 18:27:18 volumio volumio[907]: info: ------------------------------------------- Oct 17 18:27:18 volumio volumio[907]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 17 18:27:18 volumio mpd[1591]: Oct 17 18:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 18:27:18 volumio systemd[1]: Started Music Player Daemon. Oct 17 18:27:18 volumio volumio[907]: Upnp client error: Error: This socket has been ended by the other party Oct 17 18:27:18 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:18 volumio volumio[907]: error: updateQueue error: null Oct 17 18:27:18 volumio volumio[907]: error: updateQueue error: null Oct 17 18:27:18 volumio RoonBridge[1476]: 00:00:00.230 Warn: get lock file path: /tmp/.rnbhgem0- Oct 17 18:27:18 volumio jellyfin[1440]: [18:27:18] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies Oct 17 18:27:19 volumio volumio[1588]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 17 18:27:19 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:19 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:19 volumio volumio[1588]: ....++++ Oct 17 18:27:20 volumio jellyfin[1440]: [18:27:20] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Oct 17 18:27:20 volumio jellyfin[1440]: [18:27:20] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: [] Oct 17 18:27:20 volumio jellyfin[1440]: [18:27:20] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Used LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Oct 17 18:27:20 volumio jellyfin[1440]: [18:27:20] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.10.105"] Oct 17 18:27:20 volumio jellyfin[1440]: [18:27:20] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"] Oct 17 18:27:20 volumio jellyfin[1440]: [18:27:20] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist Oct 17 18:27:20 volumio jellyfin[1440]: [18:27:20] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: [] Oct 17 18:27:20 volumio volumio[1588]: ....++++ Oct 17 18:27:20 volumio volumio[1588]: e is 65537 (0x010001) Oct 17 18:27:20 volumio volumio[1588]: writing RSA key Oct 17 18:27:22 volumio RoonBridge[1476]: 00:00:10.050 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 17 18:27:22 volumio RoonBridge[1476]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 17 18:27:22 volumio RoonBridge[1476]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 17 18:27:22 volumio RoonBridge[1476]: Not Running (.o) Oct 17 18:27:22 volumio RoonBridge[1476]: 00:00:10.132 Debug: PathForResource, filename: ../.update Oct 17 18:27:22 volumio RoonBridge[1476]: 00:00:10.132 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 17 18:27:22 volumio volumio[907]: 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 17 18:27:24 volumio RoonBridge[1476]: 00:00:12.145 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 17 18:27:24 volumio RoonBridge[1476]: Running Oct 17 18:27:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 17 18:27:27 volumio volumio[907]: info: Adding plugin bluetooth to MyMusic Plugins Oct 17 18:27:27 volumio volumio[907]: info: Adding plugin multiroom to MyMusic Plugins Oct 17 18:27:27 volumio volumio[907]: info: Adding plugin metavolumio to MyMusic Plugins Oct 17 18:27:27 volumio volumio[907]: info: Adding plugin cd_controller to MyMusic Plugins Oct 17 18:27:27 volumio volumio[907]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 17 18:27:27 volumio volumio[907]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 17 18:27:27 volumio volumio[907]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 17 18:27:30 volumio volumio[907]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 17 18:27:30 volumio volumio[907]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 17 18:27:30 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:30 volumio volumio[907]: info: Starting MyVolumio Remote Streaming Endpoints Oct 17 18:27:30 volumio volumio[907]: info: MyVolumio login type: Token Oct 17 18:27:30 volumio volumio[907]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 17 18:27:30 volumio volumio[907]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 17 18:27:32 volumio volumio[907]: info: Starting Streaming Service Transparent Proxy Oct 17 18:27:32 volumio volumio[907]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 17 18:27:32 volumio volumio[907]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 17 18:27:32 volumio volumio[907]: info: Streaming services startup Oct 17 18:27:32 volumio volumio[907]: info: Starting Streaming Daemon Oct 17 18:27:32 volumio sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 17 18:27:32 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:32 volumio volumio[907]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 17 18:27:32 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:32 volumio volumio[907]: info: AutoStart - Plugin is starting Oct 17 18:27:32 volumio volumio[907]: info: CoreCommandRouter::volumioGetQueue Oct 17 18:27:32 volumio volumio[907]: info: CoreStateMachine::getQueue Oct 17 18:27:32 volumio volumio[907]: info: CorePlayQueue::getQueue Oct 17 18:27:32 volumio volumio[907]: info: AutoStart - start playing Oct 17 18:27:32 volumio volumio[907]: info: AutoStart - start playing with no specific position Oct 17 18:27:32 volumio volumio[907]: info: CoreCommandRouter::volumioPlay Oct 17 18:27:32 volumio volumio[907]: info: CoreStateMachine::play index 0 Oct 17 18:27:32 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 18:27:32 volumio volumio[907]: info: CoreStateMachine::stop Oct 17 18:27:32 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 18:27:32 volumio volumio[907]: info: CoreStateMachine::play index undefined Oct 17 18:27:32 volumio volumio[907]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 18:27:32 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:32 volumio volumio[907]: info: CoreStateMachine::startPlaybackTimer Oct 17 18:27:32 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 18:27:32 volumio volumio[907]: info: sendMpdCommand stop took 47 milliseconds Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 18:27:32 volumio volumio[907]: error: Cannot start Volumio Streaming Daemon Oct 17 18:27:32 volumio volumio[907]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 17 18:27:32 volumio volumio[907]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 17 18:27:32 volumio volumio[907]: info: Oct 17 18:27:32 volumio volumio[907]: ---------------------------- MPD announces system playlist update Oct 17 18:27:32 volumio volumio[907]: info: Ignoring MPD Status Update Oct 17 18:27:32 volumio volumio[907]: info: sendMpdCommand clear took 51 milliseconds Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Oct 17 18:27:32 volumio volumio[907]: info: Oct 17 18:27:32 volumio volumio[907]: ---------------------------- MPD announces system playlist update Oct 17 18:27:32 volumio volumio[907]: info: Ignoring MPD Status Update Oct 17 18:27:32 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:32 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:32 volumio volumio[907]: error: updateQueue error: null Oct 17 18:27:32 volumio volumio[907]: info: Oct 17 18:27:32 volumio volumio[907]: ---------------------------- MPD announces system playlist update Oct 17 18:27:32 volumio volumio[907]: info: Ignoring MPD Status Update Oct 17 18:27:32 volumio volumio[907]: info: ------------------------------ 76ms Oct 17 18:27:32 volumio volumio[907]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 69 milliseconds Oct 17 18:27:32 volumio volumio[907]: info: ------------------------------ 84ms Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand play Oct 17 18:27:32 volumio volumio[907]: info: Oct 17 18:27:32 volumio volumio[907]: ---------------------------- MPD announces system playlist update Oct 17 18:27:32 volumio volumio[907]: info: Ignoring MPD Status Update Oct 17 18:27:32 volumio volumio[907]: info: Oct 17 18:27:32 volumio volumio[907]: ---------------------------- MPD announces state update: player Oct 17 18:27:32 volumio volumio[907]: info: Oct 17 18:27:32 volumio volumio[907]: ---------------------------- MPD announces state update: player Oct 17 18:27:32 volumio volumio[907]: info: ------------------------------ 155ms Oct 17 18:27:32 volumio volumio[907]: info: sendMpdCommand play took 154 milliseconds Oct 17 18:27:32 volumio volumio[907]: info: ControllerMpd::getState Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Oct 17 18:27:32 volumio volumio[907]: info: ------------------------------ 156ms Oct 17 18:27:32 volumio volumio[907]: info: ControllerMpd::getState Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Oct 17 18:27:32 volumio volumio[907]: info: Oct 17 18:27:32 volumio volumio[907]: ---------------------------- MPD announces state update: player Oct 17 18:27:32 volumio volumio[907]: info: ControllerMpd::getState Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Oct 17 18:27:32 volumio volumio[907]: info: sendMpdCommand status took 42 milliseconds Oct 17 18:27:32 volumio volumio[907]: info: sendMpdCommand status took 28 milliseconds Oct 17 18:27:32 volumio volumio[907]: info: sendMpdCommand status took 18 milliseconds Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::parseState Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::parseState Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::parseState Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 18:27:32 volumio volumio[907]: info: Oct 17 18:27:32 volumio volumio[907]: ---------------------------- MPD announces state update: player Oct 17 18:27:32 volumio volumio[907]: info: ControllerMpd::getState Oct 17 18:27:32 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Oct 17 18:27:32 volumio volumio[907]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 17 18:27:33 volumio volumio[907]: info: sendMpdCommand playlistinfo took 55 milliseconds Oct 17 18:27:33 volumio volumio[907]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 17 18:27:33 volumio volumio[907]: info: sendMpdCommand status took 49 milliseconds Oct 17 18:27:33 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Oct 17 18:27:33 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Oct 17 18:27:33 volumio volumio[907]: verbose: ControllerMpd::parseState Oct 17 18:27:33 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 18:27:33 volumio volumio[907]: info: ControllerMpd::pushState Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::servicePushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 18:27:33 volumio volumio[907]: verbose: CURRENT POSITION 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop Oct 17 18:27:33 volumio volumio[907]: info: ControllerMpd::pushState Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::servicePushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 18:27:33 volumio volumio[907]: verbose: CURRENT POSITION 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Oct 17 18:27:33 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:33 volumio volumio[907]: info: ControllerMpd::pushState Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::servicePushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 18:27:33 volumio volumio[907]: verbose: CURRENT POSITION 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Oct 17 18:27:33 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:33 volumio volumio[907]: info: ------------------------------ 272ms Oct 17 18:27:33 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 17 18:27:33 volumio volumio[907]: info: ------------------------------ 386ms Oct 17 18:27:33 volumio volumio[907]: info: ------------------------------ 351ms Oct 17 18:27:33 volumio volumio[907]: error: MyVolumio Custom Token format not valid, refreshing it Oct 17 18:27:33 volumio volumio[907]: 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 17 18:27:33 volumio volumio[907]: info: sendMpdCommand playlistinfo took 323 milliseconds Oct 17 18:27:33 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Oct 17 18:27:33 volumio volumio[907]: info: ControllerMpd::pushState Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::servicePushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 18:27:33 volumio volumio[907]: verbose: CURRENT POSITION 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::syncState stateService play Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus play Oct 17 18:27:33 volumio volumio[907]: info: Received an update from plugin. extracting info from payload Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:33 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:27:33 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:33 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:27:33 volumio volumio[907]: info: ------------------------------ 451ms Oct 17 18:27:34 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 18:27:34 volumio volumio[907]: STREAMING PROXY: Starting server on port 3245 Oct 17 18:27:34 volumio volumio[907]: Node JS runtime: 14 Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 18:27:35 volumio volumio[907]: info: MyVolumio login type: Token Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:35 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 18:27:35 volumio volumio[907]: info: Received Get System Info Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:27:35 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:35 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:35 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:35 volumio volumio[907]: info: Listing playlists Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:35 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 18:27:35 volumio volumio[907]: info: Received Get System Info Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:27:35 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:35 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 17 18:27:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 18:27:35 volumio volumio[907]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 17 18:27:36 volumio jellyfin[1440]: [18:27:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Oct 17 18:27:36 volumio jellyfin[1440]: [18:27:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Oct 17 18:27:36 volumio jellyfin[1440]: [18:27:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Oct 17 18:27:36 volumio jellyfin[1440]: [18:27:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Oct 17 18:27:36 volumio jellyfin[1440]: [18:27:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Oct 17 18:27:37 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 17 18:27:37 volumio volumio[907]: info: MyVolumio login type: Token Oct 17 18:27:37 volumio volumio[907]: info: MyVolumio token set successfully Oct 17 18:27:37 volumio volumio[907]: info: MYVOLUMIO: Adding device Oct 17 18:27:37 volumio volumio[907]: info: MYVOLUMIO: Evaluating Server Oct 17 18:27:37 volumio jellyfin[1440]: [18:27:37] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["DisableTranscodingThrottling", "CreateLoggingConfigHeirarchy", "MigrateActivityLogDatabase", "RemoveDuplicateExtras", "MigrateUserDatabase", "MigrateDisplayPreferencesDatabase", "RemoveDownloadImagesInAdvance", "MigrateAuthenticationDatabase", "FixPlaylistOwner", "MigrateRatingLevels", "FixAudioData"] Oct 17 18:27:37 volumio jellyfin[1440]: [18:27:37] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Oct 17 18:27:38 volumio volumio[907]: info: MyVolumio status changed Oct 17 18:27:38 volumio volumio[907]: info: Streaming services startup Oct 17 18:27:38 volumio volumio[907]: info: Starting Streaming Daemon Oct 17 18:27:38 volumio volumio[907]: info: Removing browser output: myVolumio user plan is not superstar Oct 17 18:27:38 volumio volumio[907]: info: Removing audio output: Oct 17 18:27:38 volumio volumio[907]: info: Stoppping Tunnel 1 Oct 17 18:27:38 volumio sudo[1939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 17 18:27:38 volumio sudo[1939]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:38 volumio sudo[1939]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 17 18:27:38 volumio sudo[1942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 17 18:27:38 volumio volumio[907]: info: MyVolumio login type: Token Oct 17 18:27:38 volumio sudo[1942]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:38 volumio sudo[1942]: pam_unix(sudo:session): session closed for user root Oct 17 18:27:38 volumio volumio[907]: info: Setting Geolocation for MyVolumio to as1 Oct 17 18:27:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:38 volumio volumio[907]: error: Cannot start Volumio Streaming Daemon Oct 17 18:27:38 volumio volumio[907]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 17 18:27:38 volumio volumio[907]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 17 18:27:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 18:27:38 volumio volumio[907]: info: Remote SSH Stopped Oct 17 18:27:39 volumio volumio[907]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 17 18:27:39 volumio volumio[907]: info: Updating MyVolumio device info Oct 17 18:27:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 18:27:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 18:27:40 volumio volumio[907]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 17 18:27:40 volumio volumio-remote-updater[661]: No test mode Oct 17 18:27:40 volumio volumio-remote-updater[661]: No alpha test mode Oct 17 18:27:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 17 18:27:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 18:27:40 volumio volumio[907]: info: Received Get System Info Oct 17 18:27:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:27:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:27:40 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:27:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:27:41 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 18:27:41 volumio volumio[907]: info: Received Get System Info Oct 17 18:27:41 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:27:41 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:27:41 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:27:41 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:41 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:41 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:27:42 volumio jellyfin[1440]: [18:27:42] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Oct 17 18:27:42 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 18:27:43 volumio jellyfin[1440]: [18:27:43] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Oct 17 18:27:43 volumio jellyfin[1440]: [18:27:43] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2024-10-18 03:00:00.000 +08:00, which is 08:32:16.7656912 from now. Oct 17 18:27:43 volumio jellyfin[1440]: [18:27:43] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2024-10-18 02:00:00.000 +08:00, which is 07:32:16.7455982 from now. Oct 17 18:27:43 volumio volumio[907]: info: MYVOLUMIO: Adding device Oct 17 18:27:43 volumio volumio[907]: info: MYVOLUMIO: Evaluating Server Oct 17 18:27:43 volumio jellyfin[1440]: [18:27:43] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Oct 17 18:27:44 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:44 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:44 volumio sudo[1982]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 17 18:27:44 volumio sudo[1982]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:27:44 volumio volumio[907]: info: Setting Geolocation for MyVolumio to as1 Oct 17 18:27:44 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:44 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:44 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:44 volumio jellyfin[1440]: [18:27:44] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available decoders: ["libdav1d", "av1", "av1_cuvid", "av1_qsv", "h264", "h264_qsv", "h264_cuvid", "hevc", "hevc_qsv", "hevc_cuvid", "mpeg2video", "mpeg2_qsv", "mpeg2_cuvid", "mpeg4", "mpeg4_cuvid", "msmpeg4", "vc1_qsv", "vc1_cuvid", "vp8", "libvpx", "vp8_cuvid", "vp8_qsv", "vp9", "libvpx-vp9", "vp9_cuvid", "vp9_qsv", "aac", "ac3", "dca", "flac", "mp3", "truehd"] Oct 17 18:27:44 volumio jellyfin[1440]: [18:27:44] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available encoders: ["libsvtav1", "av1_nvenc", "av1_qsv", "av1_amf", "av1_vaapi", "libx264", "h264_amf", "h264_nvenc", "h264_qsv", "h264_v4l2m2m", "h264_vaapi", "libx265", "hevc_amf", "hevc_nvenc", "hevc_qsv", "hevc_vaapi", "mjpeg_qsv", "mjpeg_vaapi", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "libfdk_aac", "ac3", "alac", "dca", "flac", "libmp3lame", "libopus", "truehd", "libvorbis", "srt"] Oct 17 18:27:44 volumio jellyfin[1440]: [18:27:44] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available filters: ["deinterlace_qsv", "deinterlace_vaapi", "hwupload_cuda", "hwupload_vaapi", "libplacebo", "overlay_opencl", "overlay_qsv", "overlay_vaapi", "overlay_vulkan", "overlay_cuda", "procamp_vaapi", "scale_cuda", "scale_opencl", "scale_qsv", "scale_vaapi", "scale_vulkan", "tonemap_cuda", "tonemap_opencl", "tonemap_vaapi", "vpp_qsv", "yadif_cuda", "zscale", "alphasrc"] Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 18:27:45 volumio volumio[907]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:45 volumio volumio[907]: info: Updating MyVolumio device info Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 18:27:45 volumio volumio[907]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 17 18:27:45 volumio volumio[907]: info: Received Get System Version Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 18:27:45 volumio volumio[907]: info: Received Get System Info Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:27:45 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:27:45 volumio volumio[907]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 17 18:27:46 volumio volumio[907]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 17 18:27:46 volumio jellyfin[1440]: [18:27:46] [INF] [13] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds Oct 17 18:27:47 volumio jellyfin[1440]: [18:27:47] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] Oct 17 18:27:47 volumio volumio[907]: 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 Oct 17 18:27:47 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:47 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:50 volumio volumio[907]: 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: 8 Oct 17 18:27:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 17 18:27:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 17 18:27:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 17 18:27:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetBrowseSources Oct 17 18:27:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 18:27:50 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:27:50 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:27:51 volumio jellyfin[1440]: [18:27:51] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Oct 17 18:27:51 volumio jellyfin[1440]: [18:27:51] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: b1cfc0d663974d88a7e5ad9e731e53ae Oct 17 18:27:51 volumio jellyfin[1440]: [18:27:51] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Oct 17 18:27:51 volumio jellyfin[1440]: [18:27:51] [INF] [1] Main: Startup complete 0:00:39.2534214 Oct 17 18:27:51 volumio jellyfin[1440]: [18:27:51] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 4 seconds Oct 17 18:27:52 volumio volumio[907]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 17 18:27:52 volumio volumio[907]: info: Completed starting MyVolumio Plugin Oct 17 18:27:52 volumio volumio[907]: info: BOOT COMPLETED Oct 17 18:27:52 volumio volumio[907]: [Metrics] CommandRouter: 56s 762.85ms Oct 17 18:27:52 volumio volumio[907]: info: CoreCommandRouter::volumiosetStartupVolume Oct 17 18:27:52 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 18:27:52 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 18:27:52 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent Oct 17 18:27:52 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent Oct 17 18:27:52 volumio volumio[907]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 17 18:27:52 volumio volumio[907]: aplay: main:828: audio open error: Device or resource busy Oct 17 18:27:53 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 17 18:27:53 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 18:27:53 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 17 18:28:10 volumio sudo[2158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 18:28:10 volumio sudo[2158]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:28:10 volumio sudo[2158]: pam_unix(sudo:session): session closed for user root Oct 17 18:28:10 volumio sudo[2160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 18:28:10 volumio sudo[2160]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:28:10 volumio sudo[2160]: pam_unix(sudo:session): session closed for user root Oct 17 18:28:10 volumio volumio[907]: verbose: New Socket.io Connection to 192.168.10.105 from 192.168.10.137 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:10 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 18:28:10 volumio volumio[907]: info: Received Get System Info Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:28:10 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:10 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:10 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:10 volumio volumio[907]: info: Listing playlists Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 18:28:10 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 18:28:11 volumio sudo[2168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 18:28:11 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:28:11 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root Oct 17 18:28:11 volumio sudo[2170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 18:28:11 volumio sudo[2170]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:28:11 volumio sudo[2170]: pam_unix(sudo:session): session closed for user root Oct 17 18:28:11 volumio volumio[907]: verbose: New Socket.io Connection to 192.168.10.105 from 192.168.10.137 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:11 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 18:28:11 volumio volumio[907]: info: Received Get System Info Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:28:11 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:11 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:11 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:11 volumio volumio[907]: info: Listing playlists Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 18:28:11 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 18:28:12 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:12 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 18:28:13 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 18:28:13 volumio volumio[907]: info: Received Get System Info Oct 17 18:28:13 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:28:13 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:28:13 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:28:13 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:13 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:13 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:28:13 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 17 18:28:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 18:28:14 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 17 18:28:17 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 18:28:17 volumio volumio[907]: info: Received Get System Info Oct 17 18:28:17 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:28:17 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:28:17 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:28:17 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:17 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:17 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:28:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 18:28:23 volumio volumio-remote-updater[661]: [2024-10-17 18:28:23] [disconnect] Disconnect close local:[1000] remote:[1000] Oct 17 18:28:23 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Oct 17 18:28:23 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Oct 17 18:28:23 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Oct 17 18:28:23 volumio systemd[1]: Stopped volumio-remote-updater.service. Oct 17 18:28:23 volumio systemd[1]: Started volumio-remote-updater.service. Oct 17 18:28:24 volumio volumio-remote-updater[2201]: Error: No active session Oct 17 18:28:24 volumio volumio-remote-updater[2201]: [2024-10-17 18:28:24] [connect] Successful connection Oct 17 18:28:24 volumio volumio-remote-updater[2201]: [2024-10-17 18:28:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729160904 101 Oct 17 18:28:24 volumio volumio[907]: 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: 8 Oct 17 18:28:25 volumio volumio[907]: info: Starting Uninstall of plugin system_controller - autostart Oct 17 18:28:25 volumio volumio[907]: info: Uninstalling plugin autostart Oct 17 18:28:25 volumio volumio[907]: info: AutoStart - onStop Oct 17 18:28:25 volumio volumio[907]: info: Disabling plugin autostart Oct 17 18:28:25 volumio volumio[907]: info: Checking if uninstall.sh is present Oct 17 18:28:25 volumio volumio[907]: info: Executing uninstall.sh Oct 17 18:28:25 volumio sudo[2214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/autostart/uninstall.sh Oct 17 18:28:25 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:28:25 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root Oct 17 18:28:25 volumio volumio[907]: info: Uninstall script completed Oct 17 18:28:25 volumio volumio[907]: info: Removing plugin autostart from configuration Oct 17 18:28:25 volumio volumio[907]: info: Successfully removed autostart configuration files Oct 17 18:28:25 volumio volumio[907]: info: Plugin folders cleanup Oct 17 18:28:25 volumio volumio[907]: info: Scanning into folder /volumio/app/plugins/ Oct 17 18:28:25 volumio volumio[907]: info: Scanning category audio_interface Oct 17 18:28:25 volumio volumio[907]: info: Scanning category miscellanea Oct 17 18:28:25 volumio volumio[907]: info: Scanning category music_service Oct 17 18:28:25 volumio volumio[907]: info: Scanning category plugins.json Oct 17 18:28:25 volumio volumio[907]: info: Scanning category system_controller Oct 17 18:28:25 volumio volumio[907]: info: Scanning category user_interface Oct 17 18:28:25 volumio volumio[907]: info: Scanning into folder /data/plugins/ Oct 17 18:28:25 volumio volumio[907]: info: Scanning category music_service Oct 17 18:28:25 volumio volumio[907]: info: Scanning category system_controller Oct 17 18:28:25 volumio volumio[907]: info: Cleaning folder for autostart Oct 17 18:28:25 volumio volumio[907]: info: Scanning category user_interface Oct 17 18:28:25 volumio volumio[907]: info: Plugin folders cleanup completed Oct 17 18:28:25 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 18:28:25 volumio volumio[907]: info: [{"prettyName":"Roon Bridge","name":"RoonBridge","category":"music_service","version":"1.0.0","icon":"fas fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Jellyfin","name":"jellyfin","category":"music_service","version":"1.1.1","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Jellyfin Server","name":"jellyfin_server","category":"music_service","version":"1.0.2","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Logitech Media Server","name":"lms","category":"music_service","version":"1.2.6","icon":"fa fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"miniDLNA","name":"minidlna","category":"music_service","version":"3.1.12","icon":"fa-share-alt","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"PeppyMeter Screensaver","name":"peppy_screensaver","category":"user_interface","version":"2.2.1","icon":"fa-lightbulb-o","isManuallyInstalled":true,"enabled":true,"active":true}] Oct 17 18:28:25 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 17 18:28:30 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 18:28:30 volumio volumio[907]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 17 18:28:30 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 17 18:28:30 volumio volumio[907]: info: Received Get System Version Oct 17 18:28:30 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 18:28:30 volumio volumio[907]: info: Received Get System Info Oct 17 18:28:30 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 18:28:30 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 18:28:30 volumio volumio[907]: info: Discovery: Getting this device information Oct 17 18:28:30 volumio volumio[907]: info: CoreCommandRouter::volumioGetState Oct 17 18:28:30 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:30 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 18:28:34 volumio volumio[907]: info: Starting Uninstall of plugin music_service - minidlna Oct 17 18:28:34 volumio volumio[907]: info: Uninstalling plugin minidlna Oct 17 18:28:34 volumio volumio[907]: info: minidlna: Stopping minidlna.service Oct 17 18:28:34 volumio sudo[2245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop minidlna.service Oct 17 18:28:34 volumio sudo[2245]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:28:34 volumio systemd[1]: Stopping MiniDLNA lightweight DLNA/UPnP-AV server... Oct 17 18:28:34 volumio systemd[1]: minidlna.service: Succeeded. Oct 17 18:28:34 volumio systemd[1]: Stopped MiniDLNA lightweight DLNA/UPnP-AV server. Oct 17 18:28:34 volumio sudo[2245]: pam_unix(sudo:session): session closed for user root Oct 17 18:28:34 volumio volumio[907]: info: minidlna: systemctl stop minidlna.service succeeded. Oct 17 18:28:34 volumio volumio[907]: info: Disabling plugin minidlna Oct 17 18:28:34 volumio volumio[907]: info: Checking if uninstall.sh is present Oct 17 18:28:34 volumio volumio[907]: info: Executing uninstall.sh Oct 17 18:28:34 volumio sudo[2253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/minidlna/uninstall.sh Oct 17 18:28:34 volumio sudo[2253]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 18:28:40 volumio systemd[1]: Reloading. Oct 17 18:28:41 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 17 18:28:41 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 17 18:28:41 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 17 18:28:41 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 17 18:28:41 volumio systemd[1]: Reloading. Oct 17 18:28:41 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 17 18:28:42 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 17 18:28:42 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 17 18:28:44 volumio systemd[1]: Reloading. Oct 17 18:28:44 volumio jellyfin[1440]: [18:28:44] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 1 seconds Oct 17 18:28:44 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 17 18:28:44 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 17 18:28:44 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 17 18:28:45 volumio systemd[1]: Reloading. Oct 17 18:28:45 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 17 18:28:45 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 17 18:28:45 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 17 18:28:46 volumio systemd[1]: Reloading. Oct 17 18:28:47 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 17 18:28:47 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 17 18:28:47 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 17 18:28:48 volumio systemd[1]: Reloading. Oct 17 18:28:49 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 17 18:28:49 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 17 18:28:49 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 17 18:28:49 volumio sudo[2253]: pam_unix(sudo:session): session closed for user root Oct 17 18:28:49 volumio volumio[907]: info: Uninstall script completed Oct 17 18:28:49 volumio volumio[907]: info: Removing plugin minidlna from configuration Oct 17 18:28:50 volumio volumio[907]: info: Successfully removed minidlna configuration files Oct 17 18:28:50 volumio volumio[907]: info: Plugin folders cleanup Oct 17 18:28:50 volumio volumio[907]: info: Scanning into folder /volumio/app/plugins/ Oct 17 18:28:50 volumio volumio[907]: info: Scanning category audio_interface Oct 17 18:28:50 volumio volumio[907]: info: Scanning category miscellanea Oct 17 18:28:50 volumio volumio[907]: info: Scanning category music_service Oct 17 18:28:50 volumio volumio[907]: info: Scanning category plugins.json Oct 17 18:28:50 volumio volumio[907]: info: Scanning category system_controller Oct 17 18:28:50 volumio volumio[907]: info: Scanning category user_interface Oct 17 18:28:50 volumio volumio[907]: info: Scanning into folder /data/plugins/ Oct 17 18:28:50 volumio volumio[907]: info: Scanning category music_service Oct 17 18:28:50 volumio volumio[907]: info: Cleaning folder for minidlna Oct 17 18:28:50 volumio volumio[907]: info: Scanning category system_controller Oct 17 18:28:50 volumio volumio[907]: info: Scanning category user_interface Oct 17 18:28:50 volumio volumio[907]: info: Plugin folders cleanup completed Oct 17 18:28:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 18:28:50 volumio volumio[907]: info: [{"prettyName":"Roon Bridge","name":"RoonBridge","category":"music_service","version":"1.0.0","icon":"fas fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Jellyfin","name":"jellyfin","category":"music_service","version":"1.1.1","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Jellyfin Server","name":"jellyfin_server","category":"music_service","version":"1.0.2","icon":"fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Logitech Media Server","name":"lms","category":"music_service","version":"1.2.6","icon":"fa fa-music","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"PeppyMeter Screensaver","name":"peppy_screensaver","category":"user_interface","version":"2.2.1","icon":"fa-lightbulb-o","isManuallyInstalled":true,"enabled":true,"active":true}] Oct 17 18:28:50 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 17 18:28:55 volumio volumio[907]: info: CoreCommandRouter::volumioPause Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::pause Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::stPlaybackTimer Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::servicePause Oct 17 18:28:55 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:55 volumio volumio[907]: info: CoreCommandRouter::servicePause Oct 17 18:28:55 volumio volumio[907]: info: ControllerMpd::pause Oct 17 18:28:55 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand pause Oct 17 18:28:55 volumio volumio[907]: info: sendMpdCommand pause took 5 milliseconds Oct 17 18:28:55 volumio volumio[907]: info: Oct 17 18:28:55 volumio volumio[907]: ---------------------------- MPD announces state update: player Oct 17 18:28:55 volumio volumio[907]: info: ControllerMpd::getState Oct 17 18:28:55 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Oct 17 18:28:55 volumio volumio[907]: info: Oct 17 18:28:55 volumio volumio[907]: ---------------------------- MPD announces state update: player Oct 17 18:28:55 volumio volumio[907]: info: ControllerMpd::getState Oct 17 18:28:55 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status Oct 17 18:28:55 volumio volumio[907]: info: sendMpdCommand status took 4 milliseconds Oct 17 18:28:55 volumio volumio[907]: info: sendMpdCommand status took 2 milliseconds Oct 17 18:28:55 volumio volumio[907]: verbose: ControllerMpd::parseState Oct 17 18:28:55 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 18:28:55 volumio volumio[907]: verbose: ControllerMpd::parseState Oct 17 18:28:55 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 18:28:55 volumio volumio[907]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 18:28:55 volumio volumio[907]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 18:28:55 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Oct 17 18:28:55 volumio volumio[907]: verbose: ControllerMpd::parseTrackInfo Oct 17 18:28:55 volumio volumio[907]: info: ControllerMpd::pushState Oct 17 18:28:55 volumio volumio[907]: info: CoreCommandRouter::servicePushState Oct 17 18:28:55 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:55 volumio volumio[907]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82915,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 18:28:55 volumio volumio[907]: verbose: CURRENT POSITION 0 Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::syncState stateService pause Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus pause Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:28:55 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:55 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 18:28:55 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::stPlaybackTimer Oct 17 18:28:55 volumio volumio[907]: info: ControllerMpd::pushState Oct 17 18:28:55 volumio volumio[907]: info: CoreCommandRouter::servicePushState Oct 17 18:28:55 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:55 volumio volumio[907]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82915,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 18:28:55 volumio volumio[907]: verbose: CURRENT POSITION 0 Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::syncState stateService pause Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus pause Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::pushState Oct 17 18:28:55 volumio volumio[907]: info: CorePlayQueue::getTrack 0 Oct 17 18:28:55 volumio volumio[907]: info: CoreCommandRouter::volumioPushState Oct 17 18:28:55 volumio volumio[907]: info: CoreStateMachine::stPlaybackTimer Oct 17 18:28:55 volumio volumio[907]: info: ------------------------------ 63ms Oct 17 18:28:55 volumio volumio[907]: info: ------------------------------ 61ms Oct 17 18:28:55 volumio volumio[907]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 18:28:55 volumio volumio[907]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 17 18:28:55 volumio volumio[907]: at Object.readdirSync (fs.js:1048:3) Oct 17 18:28:55 volumio volumio[907]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 17 18:28:55 volumio volumio[907]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 17 18:28:55 volumio volumio[907]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 17 18:28:55 volumio volumio[907]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 17 18:28:55 volumio volumio[907]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 17 18:28:55 volumio volumio[907]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 17 18:28:55 volumio volumio[907]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 17 18:28:55 volumio volumio[907]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 17 18:28:55 volumio volumio[907]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 17 18:28:55 volumio volumio[907]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 17 18:28:55 volumio volumio[907]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 17 18:28:55 volumio volumio[907]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 17 18:28:55 volumio volumio[907]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 17 18:28:55 volumio volumio[907]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 17 18:28:55 volumio volumio[907]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 17 18:28:55 volumio volumio[907]: errno: -20, Oct 17 18:28:55 volumio volumio[907]: syscall: 'scandir', Oct 17 18:28:55 volumio volumio[907]: code: 'ENOTDIR', Oct 17 18:28:55 volumio volumio[907]: path: '/tmp/peppyrunning' Oct 17 18:28:55 volumio volumio[907]: } Oct 17 18:28:55 volumio volumio[907]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 18:28:55 volumio sudo[2524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-17 18:27 Oct 17 18:28:55 volumio sudo[2524]: 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"