-- Logs begin at Tue 2025-06-24 06:55:25 JST, end at Tue 2025-06-24 06:57:01 JST. -- Jun 24 06:56:01 volumio volumio[1298]: info: Loading plugin "network"... Jun 24 06:56:01 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 24 06:56:01 volumio volumio[1298]: info: Refreshing Cached IP Addresses Jun 24 06:56:01 volumio sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 06:56:01 volumio sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:01 volumio sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 06:56:01 volumio sudo[1336]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:01 volumio sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:01 volumio volumio[1298]: info: Loading plugin "services"... Jun 24 06:56:01 volumio volumio[1298]: info: Loading plugin "alsa_controller"... Jun 24 06:56:01 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:01 volumio sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 24 06:56:01 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:01 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:01 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 06:56:01 volumio volumio[1298]: info: Loading plugin "wizard"... Jun 24 06:56:02 volumio volumio[1298]: info: Loading plugin "networkfs"... Jun 24 06:56:02 volumio volumio[1298]: info: Starting Udev Watcher for removable devices Jun 24 06:56:02 volumio volumio[1298]: info: Ignoring mount for partition: boot Jun 24 06:56:02 volumio volumio[1298]: info: Ignoring mount for partition: volumio Jun 24 06:56:02 volumio volumio[1298]: info: Ignoring mount for partition: volumio_data Jun 24 06:56:02 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 06:56:02 volumio volumio[1298]: info: Loading plugin "volumio_command_line_client"... Jun 24 06:56:02 volumio volumio[1298]: info: Loading plugin "upnp"... Jun 24 06:56:02 volumio volumio[1298]: info: [1750715762200] Starting Upmpd Daemon Jun 24 06:56:02 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 06:56:02 volumio volumio[1298]: info: Loading plugin "my_music"... Jun 24 06:56:02 volumio volumio[1298]: info: Loading plugin "mpd"... Jun 24 06:56:03 volumio volumio[1298]: info: Loading plugin "upnp_browser"... Jun 24 06:56:04 volumio dhcpcd[1250]: eth0: carrier lost Jun 24 06:56:04 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Down Jun 24 06:56:04 volumio volumio[1298]: info: Loading plugin "alarm-clock"... Jun 24 06:56:04 volumio avahi-daemon[727]: Withdrawing address record for 192.168.223.251 on eth0. Jun 24 06:56:04 volumio avahi-daemon[727]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 24 06:56:04 volumio avahi-daemon[727]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 24 06:56:04 volumio dhcpcd[1250]: eth0: deleting route to 192.168.223.0/24 Jun 24 06:56:04 volumio dhcpcd[1250]: eth0: deleting default route via 192.168.223.1 Jun 24 06:56:04 volumio ifplugd(eth0)[976]: Link beat lost. Jun 24 06:56:04 volumio volumio[1298]: info: Loading plugin "airplay_emulation"... Jun 24 06:56:04 volumio volumio[1298]: info: Starting Shairport Sync Jun 24 06:56:04 volumio volumio[1298]: info: Loading plugin "last_100"... Jun 24 06:56:04 volumio volumio[1298]: info: Loading plugin "webradio"... Jun 24 06:56:05 volumio volumio[1298]: info: Loading plugin "i2s_dacs"... Jun 24 06:56:05 volumio volumio[1298]: info: I2S DAC not set, start Auto-detection Jun 24 06:56:05 volumio volumio[1298]: info: Loading plugin "volumiodiscovery"... Jun 24 06:56:05 volumio volumio[1298]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 06:56:05 volumio volumio[1298]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 06:56:05 volumio volumio[1298]: *** WARNING *** For more information see Jun 24 06:56:05 volumio node[1298]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 24 06:56:05 volumio volumio[1298]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 06:56:05 volumio volumio[1298]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 06:56:05 volumio volumio[1298]: *** WARNING *** For more information see Jun 24 06:56:05 volumio node[1298]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 06:56:05 volumio node[1298]: *** WARNING *** For more information see Jun 24 06:56:05 volumio node[1298]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 24 06:56:05 volumio node[1298]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 24 06:56:05 volumio node[1298]: *** WARNING *** For more information see Jun 24 06:56:05 volumio volumio[1298]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 24 06:56:05 volumio volumio[1298]: info: Discovery: Started advertising with name: Volumio Jun 24 06:56:05 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 24 06:56:05 volumio volumio[1298]: info: Loading plugin "outputs"... Jun 24 06:56:05 volumio volumio[1298]: info: Loading plugin "albumart"... Jun 24 06:56:05 volumio volumio[1298]: info: Plugin example_plugin is not enabled Jun 24 06:56:05 volumio volumio[1298]: info: Loading plugin "inputs"... Jun 24 06:56:05 volumio volumio[1298]: info: Loading plugin "updater_comm"... Jun 24 06:56:05 volumio ntpd[1019]: Deleting interface #3 eth0, 192.168.223.251#123, interface stats: received=62, sent=62, dropped=0, active_time=26 secs Jun 24 06:56:05 volumio ntpd[1019]: 45.77.20.103 local addr 192.168.223.251 -> Jun 24 06:56:05 volumio ntpd[1019]: 122.215.240.52 local addr 192.168.223.251 -> Jun 24 06:56:05 volumio ntpd[1019]: 103.131.151.30 local addr 192.168.223.251 -> Jun 24 06:56:05 volumio ntpd[1019]: 202.181.103.212 local addr 192.168.223.251 -> Jun 24 06:56:05 volumio ntpd[1019]: 103.131.151.20 local addr 192.168.223.251 -> Jun 24 06:56:05 volumio ntpd[1019]: 194.0.5.123 local addr 192.168.223.251 -> Jun 24 06:56:05 volumio ntpd[1019]: 167.179.119.205 local addr 192.168.223.251 -> Jun 24 06:56:05 volumio ntpd[1019]: 139.162.81.45 local addr 192.168.223.251 -> Jun 24 06:56:05 volumio volumio[1298]: info: Plugin mpdemulation is not enabled Jun 24 06:56:05 volumio volumio[1298]: info: Loading plugin "rest_api"... Jun 24 06:56:05 volumio volumio[1298]: info: Loading plugin "websocket"... Jun 24 06:56:06 volumio volumio[1298]: info: Starting Socket.io Server version 2.3.0 Jun 24 06:56:06 volumio volumio[1298]: info: Loading plugin "jp_radio"... Jun 24 06:56:06 volumio dhcpcd[1250]: eth0: carrier acquired Jun 24 06:56:06 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Jun 24 06:56:06 volumio dhcpcd[1250]: eth0: IAID 05:73:52:37 Jun 24 06:56:06 volumio dhcpcd[1250]: eth0: probing address 192.168.223.251/24 Jun 24 06:56:06 volumio volumio[1298]: Forking 3 albumart workers Jun 24 06:56:06 volumio dhcpcd[1250]: eth0: soliciting an IPv6 router Jun 24 06:56:06 volumio ifplugd(eth0)[976]: Link beat detected. Jun 24 06:56:08 volumio volumio[1298]: Starting albumart workers Jun 24 06:56:08 volumio volumio[1298]: Starting albumart workers Jun 24 06:56:08 volumio volumio[1298]: Starting albumart workers Jun 24 06:56:09 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 24 06:56:10 volumio dhcpcd[1250]: eth0: using static address 192.168.223.251/24 Jun 24 06:56:10 volumio dhcpcd[1250]: eth0: adding route to 192.168.223.0/24 Jun 24 06:56:10 volumio dhcpcd[1250]: eth0: adding default route via 192.168.223.1 Jun 24 06:56:10 volumio avahi-daemon[727]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 24 06:56:10 volumio avahi-daemon[727]: New relevant interface eth0.IPv4 for mDNS. Jun 24 06:56:10 volumio avahi-daemon[727]: Registering new address record for 192.168.223.251 on eth0.IPv4. Jun 24 06:56:10 volumio volumio[1298]: info: Loading plugin "lms"... Jun 24 06:56:11 volumio volumio[1298]: info: Loading i18n strings for locale ja Jun 24 06:56:11 volumio volumio[1298]: Updating browse sources language Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::initPlayerControls Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 06:56:11 volumio volumio[1298]: Express server listening on port 3000 Jun 24 06:56:11 volumio volumio[1298]: [Metrics] WebUI: 17s 970.09ms Jun 24 06:56:12 volumio volumio[1298]: info: CoreStateMachine::resetVolumioState Jun 24 06:56:12 volumio volumio[1298]: info: CoreStateMachine::getcurrentVolume Jun 24 06:56:12 volumio volumio[1298]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 06:56:12 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:12 volumio volumio[1298]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 24 06:56:12 volumio volumio[1298]: info: Completed loading Core Plugins Jun 24 06:56:12 volumio volumio[1298]: info: Preparing to generate the ALSA configuration file Jun 24 06:56:12 volumio volumio[1298]: info: Volumio Network Manager: Network status updated: 1 Jun 24 06:56:12 volumio volumio[1298]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 24 06:56:12 volumio volumio[1298]: wlan0 Interface doesn't support scanning. Jun 24 06:56:12 volumio volumio[1298]: info: Cannot use regular scanning, forcing with ap-force Jun 24 06:56:12 volumio sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 24 06:56:12 volumio sudo[1495]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:12 volumio sudo[1495]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:12 volumio volumio[1298]: command failed: No such device (-19) Jun 24 06:56:12 volumio volumio[1298]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 24 06:56:12 volumio volumio[1298]: command failed: No such device (-19) Jun 24 06:56:12 volumio sudo[1500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 24 06:56:12 volumio sudo[1500]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:12 volumio ntpd[1019]: Listen normally on 4 eth0 192.168.223.251:123 Jun 24 06:56:12 volumio ntpd[1019]: new interface(s) found: waking up resolver Jun 24 06:56:12 volumio sudo[1500]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:12 volumio sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 24 06:56:12 volumio sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:12 volumio sudo[1502]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:12 volumio volumio-remote-updater[750]: [2025-06-24 06:56:12] [connect] Successful connection Jun 24 06:56:12 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:56:12 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:12 volumio volumio[1298]: info: Reloading queue from file Jun 24 06:56:12 volumio volumio-remote-updater[750]: [2025-06-24 06:56:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750715772 101 Jun 24 06:56:12 volumio volumio[1298]: 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 Jun 24 06:56:12 volumio volumio[1298]: info: VolumeController:: Volume=70 Mute =false Jun 24 06:56:12 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:12 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:12 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 06:56:12 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:12 volumio volumio[1298]: info: CoreStateMachine::updateTrackBlock Jun 24 06:56:12 volumio volumio[1298]: info: CorePlayQueue::getTrackBlock Jun 24 06:56:12 volumio volumio[1298]: info: CoreCommandRouter::volumioRetrievevolume Jun 24 06:56:13 volumio volumio[1298]: info: Asound.conf file unchanged, so no further update is needed Jun 24 06:56:13 volumio volumio[1298]: info: Output device has changed, restarting MPD Jun 24 06:56:13 volumio volumio[1298]: info: Output device has changed, restarting Shairport Sync Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:13 volumio sudo[1520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 06:56:13 volumio sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:13 volumio sudo[1520]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:13 volumio sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 06:56:13 volumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:13 volumio systemd[1]: Stopping Music Player Daemon... Jun 24 06:56:13 volumio volumio[1298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 06:56:13 volumio volumio[1298]: info: ___________ START PLUGINS ___________ Jun 24 06:56:13 volumio volumio[1298]: info: ControllerMpd::onStart: Initializing MPD Jun 24 06:56:13 volumio volumio[1298]: info: Creating MPD Configuration file Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 06:56:13 volumio volumio[1298]: info: [1750715773287] CoreMusicLibrary::Adding element DLNAサーバー Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 06:56:13 volumio sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 24 06:56:13 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 24 06:56:13 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:13 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:13 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:13 volumio volumio[1298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:13 volumio volumio[1298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 06:56:13 volumio volumio[1298]: info: [1750715773552] CoreMusicLibrary::Adding element Last_100 Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 06:56:13 volumio volumio[1298]: info: [1750715773560] CoreMusicLibrary::Adding element Webradio Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 06:56:13 volumio systemd[1]: mpd.service: Succeeded. Jun 24 06:56:13 volumio systemd[1]: Stopped Music Player Daemon. Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 06:56:13 volumio systemd[1]: Starting Music Player Daemon... Jun 24 06:56:13 volumio volumio[1298]: info: Initializing BBC Radios Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:13 volumio sudo[1540]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 24 06:56:13 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:13 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 24 06:56:13 volumio volumio[1298]: info: [1750715773934] CoreMusicLibrary::Adding element RADIKO Jun 24 06:56:13 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 24 06:56:13 volumio volumio[1298]: Cannot find translation for source RADIKO Jun 24 06:56:13 volumio volumio[1298]: info: Volumio Calling Home Jun 24 06:56:14 volumio sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jun 24 06:56:14 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:14 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. Jun 24 06:56:14 volumio systemd[1]: Starting Logitech Media Server Daemon... Jun 24 06:56:14 volumio systemd[1]: Started Logitech Media Server Daemon. Jun 24 06:56:14 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:14 volumio systemd[1561]: logitechmediaserver.service: Failed to execute command: No such file or directory Jun 24 06:56:14 volumio systemd[1561]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Jun 24 06:56:14 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Jun 24 06:56:14 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Jun 24 06:56:14 volumio volumio[1298]: info: CoreStateMachine::setRepeat false single undefined Jun 24 06:56:14 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:14 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:14 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 06:56:14 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:14 volumio volumio[1298]: info: CoreStateMachine::setRandom null Jun 24 06:56:14 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:14 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:14 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:14 volumio volumio[1298]: info: logitechmediaserver started Jun 24 06:56:14 volumio volumio[1298]: info: MPD Permissions set Jun 24 06:56:14 volumio volumio[1298]: info: MPD Permissions set Jun 24 06:56:14 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:56:14 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:14 volumio volumio[1298]: info: Volumio called home Jun 24 06:56:14 volumio sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 24 06:56:14 volumio sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:15 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 24 06:56:15 volumio sudo[1570]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:15 volumio volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 24 06:56:15 volumio volumio[1298]: info: VolumeController:: Volume=70 Mute =false Jun 24 06:56:15 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:15 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 24 06:56:15 volumio cron[790]: 2025-06-24 06:56:15 1uTp9D-0000PU-7e Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=118 egid=124 Jun 24 06:56:15 volumio cron[790]: 2025-06-24 06:56:15 1uTp9D-0000PU-7e <= volumio@volumio U=volumio P=local S=659 Jun 24 06:56:15 volumio cron[790]: 2025-06-24 06:56:15 1uTp9D-0000PU-7e Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=118 egid=124 Jun 24 06:56:15 volumio cron[790]: exim: could not open panic log - aborting: see message(s) above Jun 24 06:56:15 volumio exim[1580]: 2025-06-24 06:56:15 1uTp9D-0000PU-7e <= volumio@volumio U=volumio P=local S=659 Jun 24 06:56:15 volumio exim[1580]: 2025-06-24 06:56:15 1uTp9D-0000PU-7e Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=118 egid=124 Jun 24 06:56:15 volumio exim[1580]: exim: could not open panic log - aborting: see message(s) above Jun 24 06:56:15 volumio CRON[806]: (volumio) MAIL (mailed 65 bytes of output but got status 0x0001 from MTA ) Jun 24 06:56:15 volumio CRON[806]: pam_unix(cron:session): session closed for user volumio Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:15 volumio volumio[1298]: info: Upmpdcli Daemon Started Jun 24 06:56:15 volumio volumio[1298]: info: Discovery: adding 55e405f5-a25e-4eee-b880-50dec7a9cf3e Jun 24 06:56:15 volumio volumio[1298]: info: Discovery: Found device Volumio Jun 24 06:56:15 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:56:15 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:15 volumio volumio[1298]: info: Starting Shairport Sync Jun 24 06:56:15 volumio volumio[1298]: info: Starting Shairport Sync Jun 24 06:56:15 volumio volumio[1298]: info: Starting Shairport Sync Jun 24 06:56:15 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 24 06:56:16 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:16 volumio sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 24 06:56:16 volumio sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:16 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:56:16 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:16 volumio sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 24 06:56:16 volumio sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 24 06:56:16 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 24 06:56:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 24 06:56:16 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:56:16 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 24 06:56:16 volumio sudo[1611]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:16 volumio sudo[1614]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:16 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:16 volumio volumio[1298]: info: Shairport-Sync Started Jun 24 06:56:16 volumio volumio[1298]: Error adding Membership: Error: addMembership EINVAL Jun 24 06:56:16 volumio volumio[1298]: info: Shairport-Sync Started Jun 24 06:56:16 volumio volumio[1298]: info: Shairport-Sync Started Jun 24 06:56:16 volumio volumio[1298]: info: JP_Radio::getting stations Jun 24 06:56:17 volumio mpd[1552]: Jun 24 06:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 24 06:56:17 volumio systemd[1]: Started Music Player Daemon. Jun 24 06:56:17 volumio sudo[1531]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:17 volumio sudo[1522]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:17 volumio volumio[1298]: Upnp client error: Error: This socket has been ended by the other party Jun 24 06:56:17 volumio volumio[1298]: info: Completed starting Core Plugins Jun 24 06:56:17 volumio volumio[1298]: info: ------------------------------------------- Jun 24 06:56:17 volumio volumio[1298]: info: ----- MyVolumio plugins startup ---- Jun 24 06:56:17 volumio volumio[1298]: info: ------------------------------------------- Jun 24 06:56:17 volumio volumio[1298]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 24 06:56:17 volumio volumio[1298]: error: MPD error: The expression evaluated to a falsy value: Jun 24 06:56:17 volumio volumio[1298]: assert.ok(self.idling) Jun 24 06:56:17 volumio volumio[1298]: error: The expression evaluated to a falsy value: Jun 24 06:56:17 volumio volumio[1298]: assert.ok(self.idling) Jun 24 06:56:17 volumio volumio[1298]: error: updateQueue error: null Jun 24 06:56:17 volumio volumio[1298]: info: MPD running with PID1552 Jun 24 06:56:17 volumio volumio[1298]: ,establishing connection Jun 24 06:56:18 volumio volumio[1298]: error: updateQueue error: null Jun 24 06:56:19 volumio volumio[1578]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 24 06:56:21 volumio volumio[1298]: info: Jun 24 06:56:21 volumio volumio[1298]: ---------------------------- Client requests Volume 30 Jun 24 06:56:21 volumio volumio[1298]: info: VolumeController::SetAlsaVolume30 Jun 24 06:56:21 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:21 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:21 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 06:56:21 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:21 volumio volumio[1298]: info: JP_Radio::Updating program listings Jun 24 06:56:23 volumio volumio[1298]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 24 06:56:23 volumio volumio[1298]: info: Jun 24 06:56:23 volumio volumio[1298]: ---------------------------- Client requests Volume NaN Jun 24 06:56:23 volumio volumio[1298]: info: VolumeController::SetAlsaVolumeNaN Jun 24 06:56:23 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:23 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:23 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 06:56:23 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 24 06:56:26 volumio volumio[1298]: info: Adding plugin bluetooth to MyMusic Plugins Jun 24 06:56:26 volumio volumio[1298]: info: Adding plugin multiroom to MyMusic Plugins Jun 24 06:56:26 volumio volumio[1298]: info: Adding plugin metavolumio to MyMusic Plugins Jun 24 06:56:26 volumio volumio[1298]: info: Adding plugin cd_controller to MyMusic Plugins Jun 24 06:56:26 volumio volumio[1298]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 24 06:56:26 volumio volumio[1298]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 24 06:56:26 volumio volumio[1298]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 24 06:56:29 volumio volumio[1298]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 24 06:56:29 volumio volumio[1298]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 24 06:56:29 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:29 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:29 volumio volumio[1298]: info: Starting MyVolumio Remote Streaming Endpoints Jun 24 06:56:29 volumio volumio[1298]: info: MyVolumio login type: Token Jun 24 06:56:29 volumio volumio[1298]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 24 06:56:29 volumio volumio[1298]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 24 06:56:32 volumio volumio[1298]: info: Starting Streaming Service Transparent Proxy Jun 24 06:56:32 volumio volumio[1298]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 24 06:56:32 volumio volumio[1298]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 24 06:56:32 volumio volumio[1298]: info: Streaming services startup Jun 24 06:56:32 volumio volumio[1298]: info: Starting Streaming Daemon Jun 24 06:56:32 volumio sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 24 06:56:32 volumio sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 24 06:56:32 volumio volumio[1298]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 24 06:56:32 volumio sudo[1702]: pam_unix(sudo:session): session closed for user root Jun 24 06:56:32 volumio volumio[1298]: error: Cannot start Volumio Streaming Daemon Jun 24 06:56:32 volumio volumio[1298]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 24 06:56:32 volumio volumio[1298]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 24 06:56:33 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:56:33 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:34 volumio volumio[1298]: error: MyVolumio Custom Token format not valid, refreshing it Jun 24 06:56:35 volumio volumio[1298]: STREAMING PROXY: Starting server on port 3245 Jun 24 06:56:35 volumio volumio[1298]: Node JS runtime: 14 Jun 24 06:56:36 volumio volumio[1578]: ..................................................................................................................................++++ Jun 24 06:56:36 volumio volumio[1298]: info: MyVolumio login type: Token Jun 24 06:56:38 volumio volumio[1298]: info: Jun 24 06:56:38 volumio volumio[1298]: ---------------------------- Client requests Volume 30 Jun 24 06:56:38 volumio volumio[1298]: info: VolumeController::SetAlsaVolume30 Jun 24 06:56:38 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:38 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:38 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 06:56:38 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:39 volumio volumio[1578]: ......................++++ Jun 24 06:56:39 volumio volumio[1578]: e is 65537 (0x010001) Jun 24 06:56:39 volumio volumio[1578]: writing RSA key Jun 24 06:56:41 volumio volumio[1298]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 24 06:56:41 volumio volumio[1298]: info: Jun 24 06:56:41 volumio volumio[1298]: ---------------------------- Client requests Volume NaN Jun 24 06:56:41 volumio volumio[1298]: info: VolumeController::SetAlsaVolumeNaN Jun 24 06:56:41 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:41 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:41 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 06:56:41 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:42 volumio ntpd[1019]: Soliciting pool server 129.250.35.251 Jun 24 06:56:43 volumio ntpd[1019]: Soliciting pool server 162.159.200.1 Jun 24 06:56:43 volumio dhcpcd[1250]: eth0: carrier lost Jun 24 06:56:43 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Down Jun 24 06:56:44 volumio avahi-daemon[727]: Withdrawing address record for 192.168.223.251 on eth0. Jun 24 06:56:44 volumio avahi-daemon[727]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 24 06:56:44 volumio avahi-daemon[727]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 24 06:56:44 volumio dhcpcd[1250]: eth0: deleting route to 192.168.223.0/24 Jun 24 06:56:44 volumio dhcpcd[1250]: eth0: deleting default route via 192.168.223.1 Jun 24 06:56:44 volumio ntpd[1019]: Soliciting pool server 45.76.221.157 Jun 24 06:56:44 volumio ntpd[1019]: Soliciting pool server 23.81.40.154 Jun 24 06:56:44 volumio ifplugd(eth0)[976]: Link beat lost. Jun 24 06:56:45 volumio ntpd[1019]: Deleting interface #4 eth0, 192.168.223.251#123, interface stats: received=21, sent=21, dropped=1, active_time=33 secs Jun 24 06:56:45 volumio ntpd[1019]: 162.159.200.1 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio ntpd[1019]: 129.250.35.251 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio ntpd[1019]: 139.162.81.45 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio ntpd[1019]: 167.179.119.205 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio ntpd[1019]: 194.0.5.123 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio ntpd[1019]: 103.131.151.20 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio ntpd[1019]: 202.181.103.212 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio ntpd[1019]: 103.131.151.30 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio ntpd[1019]: 122.215.240.52 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio ntpd[1019]: 45.77.20.103 local addr 192.168.223.251 -> Jun 24 06:56:45 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 06:56:45 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:45 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 24 06:56:45 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 24 06:56:45 volumio volumio[1298]: (node:1298) UnhandledPromiseRejectionWarning: RequestError: getaddrinfo EBUSY radiko.jp Jun 24 06:56:45 volumio volumio[1298]: at ClientRequest. (/data/plugins/music_service/jp_radio/node_modules/got/dist/source/core/index.js:970:111) Jun 24 06:56:45 volumio volumio[1298]: at Object.onceWrapper (events.js:520:26) Jun 24 06:56:45 volumio volumio[1298]: at ClientRequest.emit (events.js:412:35) Jun 24 06:56:45 volumio volumio[1298]: at ClientRequest.origin.emit (/data/plugins/music_service/jp_radio/node_modules/@szmarczak/http-timer/dist/source/index.js:43:20) Jun 24 06:56:45 volumio volumio[1298]: at Socket.socketErrorListener (_http_client.js:475:9) Jun 24 06:56:45 volumio volumio[1298]: at Socket.emit (events.js:400:28) Jun 24 06:56:45 volumio volumio[1298]: at emitErrorNT (internal/streams/destroy.js:106:8) Jun 24 06:56:45 volumio volumio[1298]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Jun 24 06:56:45 volumio volumio[1298]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 24 06:56:45 volumio volumio[1298]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) Jun 24 06:56:45 volumio volumio[1298]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 24 06:56:45 volumio volumio[1298]: (node:1298) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4925) Jun 24 06:56:45 volumio volumio[1298]: (node:1298) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jun 24 06:56:45 volumio volumio[1298]: info: MyVolumio token set successfully Jun 24 06:56:45 volumio volumio[1298]: info: MYVOLUMIO: Adding device Jun 24 06:56:45 volumio volumio[1298]: info: MYVOLUMIO: Evaluating Server Jun 24 06:56:45 volumio volumio[1298]: info: Discovery: A device disappeared from network Jun 24 06:56:45 volumio volumio[1298]: info: Discovery: Device volumio disappeared from network Jun 24 06:56:45 volumio volumio[1298]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 24 06:56:46 volumio dhcpcd[1250]: eth0: carrier acquired Jun 24 06:56:46 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Jun 24 06:56:46 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:56:46 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:46 volumio dhcpcd[1250]: eth0: IAID 05:73:52:37 Jun 24 06:56:46 volumio dhcpcd[1250]: eth0: probing address 192.168.223.251/24 Jun 24 06:56:46 volumio dhcpcd[1250]: eth0: soliciting an IPv6 router Jun 24 06:56:46 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:56:46 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:46 volumio ifplugd(eth0)[976]: Link beat detected. Jun 24 06:56:49 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:49 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:49 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 24 06:56:49 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 24 06:56:49 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 24 06:56:49 volumio volumio[1298]: info: CoreCommandRouter::volumioGetBrowseSources Jun 24 06:56:49 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 24 06:56:51 volumio dhcpcd[1250]: eth0: using static address 192.168.223.251/24 Jun 24 06:56:51 volumio avahi-daemon[727]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 24 06:56:51 volumio dhcpcd[1250]: eth0: adding route to 192.168.223.0/24 Jun 24 06:56:51 volumio avahi-daemon[727]: New relevant interface eth0.IPv4 for mDNS. Jun 24 06:56:51 volumio dhcpcd[1250]: eth0: adding default route via 192.168.223.1 Jun 24 06:56:51 volumio avahi-daemon[727]: Registering new address record for 192.168.223.251 on eth0.IPv4. Jun 24 06:56:51 volumio volumio[1298]: info: Jun 24 06:56:51 volumio volumio[1298]: ---------------------------- Client requests Volume 30 Jun 24 06:56:51 volumio volumio[1298]: info: VolumeController::SetAlsaVolume30 Jun 24 06:56:51 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:51 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:51 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 06:56:51 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:52 volumio dhcpcd[1250]: eth0: carrier lost Jun 24 06:56:52 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Down Jun 24 06:56:52 volumio avahi-daemon[727]: Withdrawing address record for 192.168.223.251 on eth0. Jun 24 06:56:52 volumio avahi-daemon[727]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 24 06:56:52 volumio avahi-daemon[727]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 24 06:56:52 volumio volumio[1298]: info: Discovery: A device disappeared from network Jun 24 06:56:52 volumio volumio[1298]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jun 24 06:56:52 volumio dhcpcd[1250]: eth0: deleting route to 192.168.223.0/24 Jun 24 06:56:52 volumio dhcpcd[1250]: eth0: deleting default route via 192.168.223.1 Jun 24 06:56:52 volumio ntpd[1019]: Soliciting pool server 2606:4700:f1::123 Jun 24 06:56:52 volumio ntpd[1019]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 24 06:56:52 volumio volumio[1298]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 24 06:56:52 volumio volumio[1298]: info: Completed starting MyVolumio Plugin Jun 24 06:56:52 volumio volumio[1298]: [Metrics] CommandRouter: 56s 961.07ms Jun 24 06:56:52 volumio volumio[1298]: info: CoreCommandRouter::volumiosetStartupVolume Jun 24 06:56:52 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 24 06:56:52 volumio volumio[1298]: info: VolumeController:: Setting startup Volume 70 Jun 24 06:56:52 volumio volumio[1298]: info: VolumeController::SetAlsaVolume70 Jun 24 06:56:52 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 24 06:56:52 volumio volumio[1298]: info: CoreCommandRouter::Close All Modals sent Jun 24 06:56:52 volumio volumio[1298]: info: CoreCommandRouter::Close All Modals sent Jun 24 06:56:52 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:52 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:52 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 06:56:52 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:52 volumio volumio[1298]: info: Jun 24 06:56:52 volumio volumio[1298]: ---------------------------- Client requests Volume NaN Jun 24 06:56:52 volumio volumio[1298]: info: VolumeController::SetAlsaVolumeNaN Jun 24 06:56:53 volumio volumio[1298]: info: CoreStateMachine::pushState Jun 24 06:56:53 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 24 06:56:53 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Jun 24 06:56:53 volumio ifplugd(eth0)[976]: Link beat lost. Jun 24 06:56:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 24 06:56:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 24 06:56:54 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 24 06:56:54 volumio dhcpcd[1250]: eth0: carrier acquired Jun 24 06:56:54 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Jun 24 06:56:54 volumio dhcpcd[1250]: eth0: IAID 05:73:52:37 Jun 24 06:56:54 volumio dhcpcd[1250]: eth0: probing address 192.168.223.251/24 Jun 24 06:56:54 volumio dhcpcd[1250]: eth0: soliciting an IPv6 router Jun 24 06:56:55 volumio ifplugd(eth0)[976]: Link beat detected. Jun 24 06:56:55 volumio nmbd[958]: [2025/06/24 06:56:55.802995, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 24 06:56:55 volumio nmbd[958]: Packet send failed to 192.168.223.255(138) ERRNO=Network is unreachable Jun 24 06:56:56 volumio volumio[1298]: info: Discovery: Browse raised the following error Error: dns service error: unknown Jun 24 06:56:58 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:56:58 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:56:59 volumio dhcpcd[1250]: eth0: using static address 192.168.223.251/24 Jun 24 06:56:59 volumio dhcpcd[1250]: eth0: adding route to 192.168.223.0/24 Jun 24 06:56:59 volumio dhcpcd[1250]: eth0: adding default route via 192.168.223.1 Jun 24 06:56:59 volumio avahi-daemon[727]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 24 06:56:59 volumio avahi-daemon[727]: New relevant interface eth0.IPv4 for mDNS. Jun 24 06:56:59 volumio avahi-daemon[727]: Registering new address record for 192.168.223.251 on eth0.IPv4. Jun 24 06:56:59 volumio volumio[1298]: info: BOOT COMPLETED Jun 24 06:57:00 volumio volumio[1298]: info: Discovery: adding 55e405f5-a25e-4eee-b880-50dec7a9cf3e Jun 24 06:57:00 volumio volumio[1298]: info: Discovery: Found device Volumio Jun 24 06:57:00 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Jun 24 06:57:00 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Jun 24 06:57:00 volumio volumio[1298]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 06:57:00 volumio volumio[1298]: Error: The value NaN is not a number Jun 24 06:57:00 volumio volumio[1298]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jun 24 06:57:00 volumio volumio[1298]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jun 24 06:57:00 volumio volumio[1298]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jun 24 06:57:00 volumio volumio[1298]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jun 24 06:57:00 volumio volumio[1298]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jun 24 06:57:00 volumio volumio[1298]: at Browser.emit (events.js:400:28) Jun 24 06:57:00 volumio volumio[1298]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jun 24 06:57:00 volumio volumio[1298]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jun 24 06:57:00 volumio volumio[1298]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jun 24 06:57:00 volumio volumio[1298]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jun 24 06:57:00 volumio volumio[1298]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jun 24 06:57:00 volumio volumio[1298]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jun 24 06:57:00 volumio volumio[1298]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jun 24 06:57:00 volumio volumio[1298]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 24 06:57:01 volumio ntpd[1019]: Listen normally on 5 eth0 192.168.223.251:123 Jun 24 06:57:01 volumio ntpd[1019]: new interface(s) found: waking up resolver Jun 24 06:57:01 volumio sudo[1954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-24 06:56 Jun 24 06:57:01 volumio sudo[1954]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="d316d397153fbb98c246d670c12f98a120b995a2" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 11 Jan 2025 04:53:42 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="nanopineo-armbian" VOLUMIO_DEVICENAME="NanoPi Neo" VOLUMIO_HASH="ce44a88e190e93c6a43956b6226fe39b"