-- Logs begin at Tue 2025-06-17 06:55:24 JST, end at Tue 2025-06-17 07:17:21 JST. -- Jun 17 07:16:10 volumio volumio-remote-updater[742]: [2025-06-17 07:16:10] [connect] Successful connection Jun 17 07:16:25 volumio volumio-remote-updater[742]: [2025-06-17 07:16:25] [connect] Successful connection Jun 17 07:16:31 volumio dhcpcd[803]: eth0: carrier lost Jun 17 07:16:31 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Down Jun 17 07:16:31 volumio avahi-daemon[723]: Withdrawing address record for 192.168.223.251 on eth0. Jun 17 07:16:31 volumio avahi-daemon[723]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 17 07:16:31 volumio avahi-daemon[723]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 17 07:16:31 volumio dhcpcd[803]: eth0: deleting route to 192.168.223.0/24 Jun 17 07:16:31 volumio dhcpcd[803]: eth0: deleting default route via 192.168.223.1 Jun 17 07:16:31 volumio ifplugd(eth0)[1005]: Link beat lost. Jun 17 07:16:33 volumio ntpd[1094]: Deleting interface #5 eth0, 192.168.223.251#123, interface stats: received=63, sent=63, dropped=0, active_time=125 secs Jun 17 07:16:33 volumio ntpd[1094]: 194.0.5.123 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 133.130.121.141 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 139.162.81.45 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 23.81.40.154 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 160.16.113.133 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 45.77.20.103 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 129.250.35.250 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 45.76.211.39 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 162.159.200.1 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 122.215.240.52 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 103.131.151.30 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 108.160.132.224 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 103.131.151.20 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio ntpd[1094]: 202.181.103.212 local addr 192.168.223.251 -> Jun 17 07:16:33 volumio dhcpcd[803]: eth0: carrier acquired Jun 17 07:16:33 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Jun 17 07:16:33 volumio dhcpcd[803]: eth0: IAID 91:77:13:c2 Jun 17 07:16:33 volumio dhcpcd[803]: eth0: probing address 192.168.223.251/24 Jun 17 07:16:34 volumio ifplugd(eth0)[1005]: Link beat detected. Jun 17 07:16:34 volumio dhcpcd[803]: eth0: soliciting an IPv6 router Jun 17 07:16:38 volumio dhcpcd[803]: eth0: using static address 192.168.223.251/24 Jun 17 07:16:38 volumio dhcpcd[803]: eth0: adding route to 192.168.223.0/24 Jun 17 07:16:38 volumio avahi-daemon[723]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 17 07:16:38 volumio dhcpcd[803]: eth0: adding default route via 192.168.223.1 Jun 17 07:16:38 volumio avahi-daemon[723]: New relevant interface eth0.IPv4 for mDNS. Jun 17 07:16:38 volumio avahi-daemon[723]: Registering new address record for 192.168.223.251 on eth0.IPv4. Jun 17 07:16:39 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 17 07:16:39 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 17 07:16:39 volumio systemd[1]: Started dynamicswap service. Jun 17 07:16:39 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 17 07:16:40 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 17 07:16:40 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 17 07:16:40 volumio systemd[1]: Started dynamicswap service. Jun 17 07:16:40 volumio systemd[1]: Stopped Volumio Backend Module. Jun 17 07:16:40 volumio systemd[1]: Started Volumio Backend Module. Jun 17 07:16:40 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 17 07:16:40 volumio ntpd[1094]: Listen normally on 6 eth0 192.168.223.251:123 Jun 17 07:16:40 volumio ntpd[1094]: new interface(s) found: waking up resolver Jun 17 07:16:40 volumio volumio-remote-updater[742]: [2025-06-17 07:16:40] [info] asio async_connect error: system:111 (Connection refused) Jun 17 07:16:40 volumio volumio-remote-updater[742]: [2025-06-17 07:16:40] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 17 07:16:40 volumio volumio-remote-updater[742]: [2025-06-17 07:16:40] [error] handle_connect error: Underlying Transport Error Jun 17 07:16:44 volumio volumio[6938]: info: ------------------------------------------- Jun 17 07:16:44 volumio volumio[6938]: info: ----- Volumio3 ---- Jun 17 07:16:44 volumio volumio[6938]: info: ------------------------------------------- Jun 17 07:16:44 volumio volumio[6938]: info: ----- System startup ---- Jun 17 07:16:44 volumio volumio[6938]: info: ------------------------------------------- Jun 17 07:16:44 volumio dhcpcd[803]: eth0: carrier lost Jun 17 07:16:44 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Down Jun 17 07:16:44 volumio avahi-daemon[723]: Withdrawing address record for 192.168.223.251 on eth0. Jun 17 07:16:44 volumio avahi-daemon[723]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 17 07:16:44 volumio avahi-daemon[723]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 17 07:16:44 volumio dhcpcd[803]: eth0: deleting route to 192.168.223.0/24 Jun 17 07:16:44 volumio dhcpcd[803]: eth0: deleting default route via 192.168.223.1 Jun 17 07:16:45 volumio ifplugd(eth0)[1005]: Link beat lost. Jun 17 07:16:45 volumio volumio[6938]: info: MYVOLUMIO Environment detected Jun 17 07:16:45 volumio volumio[6938]: info: Plugin folders cleanup Jun 17 07:16:45 volumio volumio[6938]: info: Scanning into folder /volumio/app/plugins/ Jun 17 07:16:45 volumio volumio[6938]: info: Scanning category audio_interface Jun 17 07:16:45 volumio volumio[6938]: info: Scanning category miscellanea Jun 17 07:16:45 volumio volumio[6938]: info: Scanning category music_service Jun 17 07:16:45 volumio volumio[6938]: info: Scanning category plugins.json Jun 17 07:16:45 volumio volumio[6938]: info: Scanning category system_controller Jun 17 07:16:45 volumio volumio[6938]: info: Scanning category user_interface Jun 17 07:16:45 volumio volumio[6938]: info: Scanning into folder /data/plugins/ Jun 17 07:16:45 volumio volumio[6938]: info: Scanning category music_service Jun 17 07:16:45 volumio volumio[6938]: info: Plugin folders cleanup completed Jun 17 07:16:45 volumio volumio[6938]: info: ------------------------------------------- Jun 17 07:16:45 volumio volumio[6938]: info: ----- Core plugins startup ---- Jun 17 07:16:45 volumio volumio[6938]: info: ------------------------------------------- Jun 17 07:16:45 volumio volumio[6938]: info: Loading plugins from folder /volumio/app/plugins/ Jun 17 07:16:45 volumio volumio[6938]: info: Adding plugin upnp to MyMusic Plugins Jun 17 07:16:45 volumio volumio[6938]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 17 07:16:45 volumio volumio[6938]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 17 07:16:45 volumio volumio[6938]: info: Loading plugins from folder /data/plugins/ Jun 17 07:16:45 volumio volumio[6938]: info: Loading plugin "system"... Jun 17 07:16:46 volumio volumio[6938]: info: Loading plugin "appearance"... Jun 17 07:16:46 volumio ntpd[1094]: Deleting interface #6 eth0, 192.168.223.251#123, interface stats: received=9, sent=12, dropped=2, active_time=6 secs Jun 17 07:16:46 volumio ntpd[1094]: 194.0.5.123 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 133.130.121.141 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 139.162.81.45 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 23.81.40.154 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 160.16.113.133 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 45.77.20.103 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 129.250.35.250 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 45.76.211.39 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 162.159.200.1 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 122.215.240.52 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 103.131.151.30 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 108.160.132.224 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 103.131.151.20 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio ntpd[1094]: 202.181.103.212 local addr 192.168.223.251 -> Jun 17 07:16:46 volumio dhcpcd[803]: eth0: carrier acquired Jun 17 07:16:46 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Jun 17 07:16:46 volumio dhcpcd[803]: eth0: IAID 91:77:13:c2 Jun 17 07:16:46 volumio dhcpcd[803]: eth0: probing address 192.168.223.251/24 Jun 17 07:16:47 volumio ifplugd(eth0)[1005]: Link beat detected. Jun 17 07:16:47 volumio dhcpcd[803]: eth0: soliciting an IPv6 router Jun 17 07:16:48 volumio volumio[6938]: info: Loading plugin "network"... Jun 17 07:16:48 volumio volumio[6938]: info: Refreshing Cached IP Addresses Jun 17 07:16:48 volumio sudo[7005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 07:16:48 volumio sudo[7005]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:48 volumio sudo[7005]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:48 volumio sudo[7007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 07:16:48 volumio sudo[7007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:48 volumio volumio[6938]: info: Loading plugin "services"... Jun 17 07:16:48 volumio volumio[6938]: info: Loading plugin "alsa_controller"... Jun 17 07:16:48 volumio sudo[7007]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:48 volumio sudo[7018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 17 07:16:48 volumio sudo[7018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:48 volumio sudo[7018]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:48 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 07:16:48 volumio volumio[6938]: info: Loading plugin "wizard"... Jun 17 07:16:48 volumio volumio[6938]: info: Loading plugin "networkfs"... Jun 17 07:16:48 volumio volumio[6938]: info: Starting Udev Watcher for removable devices Jun 17 07:16:48 volumio volumio[6938]: info: Ignoring mount for partition: boot Jun 17 07:16:48 volumio volumio[6938]: info: Ignoring mount for partition: volumio Jun 17 07:16:48 volumio volumio[6938]: info: Ignoring mount for partition: volumio_data Jun 17 07:16:48 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 07:16:48 volumio volumio[6938]: info: Loading plugin "volumio_command_line_client"... Jun 17 07:16:48 volumio volumio[6938]: info: Loading plugin "upnp"... Jun 17 07:16:48 volumio volumio[6938]: info: [1750112208807] Starting Upmpd Daemon Jun 17 07:16:48 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 07:16:48 volumio volumio[6938]: info: Loading plugin "my_music"... Jun 17 07:16:48 volumio volumio[6938]: info: Loading plugin "mpd"... Jun 17 07:16:49 volumio volumio[6938]: info: Loading plugin "upnp_browser"... Jun 17 07:16:50 volumio volumio-remote-updater[742]: [2025-06-17 07:16:50] [connect] Successful connection Jun 17 07:16:50 volumio volumio[6938]: info: Loading plugin "alarm-clock"... Jun 17 07:16:50 volumio volumio[6938]: info: Loading plugin "airplay_emulation"... Jun 17 07:16:50 volumio volumio[6938]: info: Starting Shairport Sync Jun 17 07:16:50 volumio volumio[6938]: info: Loading plugin "last_100"... Jun 17 07:16:50 volumio volumio[6938]: info: Loading plugin "webradio"... Jun 17 07:16:50 volumio volumio[6938]: info: Loading plugin "i2s_dacs"... Jun 17 07:16:50 volumio volumio[6938]: info: I2S DAC not set, start Auto-detection Jun 17 07:16:50 volumio volumio[6938]: info: Loading plugin "volumiodiscovery"... Jun 17 07:16:50 volumio volumio[6938]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 07:16:50 volumio volumio[6938]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 07:16:50 volumio volumio[6938]: *** WARNING *** For more information see Jun 17 07:16:50 volumio node[6938]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 17 07:16:50 volumio volumio[6938]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 07:16:50 volumio volumio[6938]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 07:16:50 volumio volumio[6938]: *** WARNING *** For more information see Jun 17 07:16:50 volumio node[6938]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 07:16:50 volumio node[6938]: *** WARNING *** For more information see Jun 17 07:16:50 volumio node[6938]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 17 07:16:50 volumio node[6938]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 17 07:16:50 volumio node[6938]: *** WARNING *** For more information see Jun 17 07:16:50 volumio volumio[6938]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 17 07:16:50 volumio volumio[6938]: info: Discovery: Started advertising with name: Volumio Jun 17 07:16:51 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 17 07:16:51 volumio volumio[6938]: info: Loading plugin "outputs"... Jun 17 07:16:51 volumio volumio[6938]: info: Loading plugin "albumart"... Jun 17 07:16:51 volumio volumio[6938]: info: Plugin example_plugin is not enabled Jun 17 07:16:51 volumio volumio[6938]: info: Loading plugin "inputs"... Jun 17 07:16:51 volumio volumio[6938]: info: Loading plugin "updater_comm"... Jun 17 07:16:51 volumio dhcpcd[803]: eth0: using static address 192.168.223.251/24 Jun 17 07:16:51 volumio dhcpcd[803]: eth0: adding route to 192.168.223.0/24 Jun 17 07:16:51 volumio dhcpcd[803]: eth0: adding default route via 192.168.223.1 Jun 17 07:16:51 volumio avahi-daemon[723]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 17 07:16:51 volumio avahi-daemon[723]: New relevant interface eth0.IPv4 for mDNS. Jun 17 07:16:51 volumio avahi-daemon[723]: Registering new address record for 192.168.223.251 on eth0.IPv4. Jun 17 07:16:51 volumio volumio[6938]: info: Plugin mpdemulation is not enabled Jun 17 07:16:51 volumio volumio[6938]: info: Loading plugin "rest_api"... Jun 17 07:16:51 volumio volumio[6938]: info: Loading plugin "websocket"... Jun 17 07:16:51 volumio volumio[6938]: info: Starting Socket.io Server version 2.3.0 Jun 17 07:16:51 volumio volumio[6938]: info: Loading plugin "jp_radio"... Jun 17 07:16:52 volumio volumio[6938]: Forking 3 albumart workers Jun 17 07:16:53 volumio ntpd[1094]: Listen normally on 7 eth0 192.168.223.251:123 Jun 17 07:16:53 volumio ntpd[1094]: new interface(s) found: waking up resolver Jun 17 07:16:54 volumio volumio[6938]: Starting albumart workers Jun 17 07:16:54 volumio volumio[6938]: Starting albumart workers Jun 17 07:16:54 volumio volumio[6938]: Starting albumart workers Jun 17 07:16:55 volumio volumio[6938]: info: Loading plugin "lms"... Jun 17 07:16:55 volumio volumio[6938]: info: Loading i18n strings for locale ja Jun 17 07:16:55 volumio volumio[6938]: Updating browse sources language Jun 17 07:16:55 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::initPlayerControls Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: Express server listening on port 3000 Jun 17 07:16:56 volumio volumio[6938]: [Metrics] WebUI: 13s 396.58ms Jun 17 07:16:56 volumio volumio[6938]: info: CoreStateMachine::resetVolumioState Jun 17 07:16:56 volumio volumio[6938]: info: CoreStateMachine::getcurrentVolume Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 07:16:56 volumio volumio[6938]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 17 07:16:56 volumio volumio[6938]: info: Completed loading Core Plugins Jun 17 07:16:56 volumio volumio[6938]: info: Preparing to generate the ALSA configuration file Jun 17 07:16:56 volumio volumio[6938]: info: Volumio Network Manager: Network status updated: 0 Jun 17 07:16:56 volumio volumio[6938]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 17 07:16:56 volumio volumio[6938]: wlan0 Interface doesn't support scanning. Jun 17 07:16:56 volumio volumio[6938]: info: Cannot use regular scanning, forcing with ap-force Jun 17 07:16:56 volumio sudo[7116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 17 07:16:56 volumio sudo[7116]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:56 volumio sudo[7116]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:56 volumio volumio[6938]: command failed: No such device (-19) Jun 17 07:16:56 volumio volumio[6938]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 17 07:16:56 volumio volumio[6938]: command failed: No such device (-19) Jun 17 07:16:56 volumio volumio[6938]: info: CoreCommandRouter::volumioGetState Jun 17 07:16:56 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:16:57 volumio volumio[6938]: info: Reloading queue from file Jun 17 07:16:57 volumio volumio[6938]: info: VolumeController:: Volume=30 Mute =false Jun 17 07:16:57 volumio volumio[6938]: info: CoreStateMachine::pushState Jun 17 07:16:57 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::volumioPushState Jun 17 07:16:57 volumio volumio[6938]: info: CoreStateMachine::updateTrackBlock Jun 17 07:16:57 volumio volumio[6938]: info: CorePlayQueue::getTrackBlock Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::volumioRetrievevolume Jun 17 07:16:57 volumio volumio[6938]: info: Asound.conf file unchanged, so no further update is needed Jun 17 07:16:57 volumio volumio[6938]: info: Output device has changed, restarting MPD Jun 17 07:16:57 volumio volumio[6938]: info: Output device has changed, restarting Shairport Sync Jun 17 07:16:57 volumio sudo[7132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 07:16:57 volumio sudo[7132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:57 volumio sudo[7132]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:57 volumio sudo[7134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 07:16:57 volumio sudo[7134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:57 volumio systemd[1]: Stopping Music Player Daemon... Jun 17 07:16:57 volumio volumio[6938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 07:16:57 volumio volumio[6938]: info: ___________ START PLUGINS ___________ Jun 17 07:16:57 volumio volumio[6938]: info: ControllerMpd::onStart: Initializing MPD Jun 17 07:16:57 volumio volumio[6938]: info: Creating MPD Configuration file Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 17 07:16:57 volumio sudo[7141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 17 07:16:57 volumio sudo[7141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 07:16:57 volumio volumio[6938]: info: [1750112217421] CoreMusicLibrary::Adding element DLNAサーバー Jun 17 07:16:57 volumio sudo[7141]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 07:16:57 volumio sudo[7143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 17 07:16:57 volumio sudo[7143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:57 volumio systemd[1]: mpd.service: Succeeded. Jun 17 07:16:57 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 07:16:57 volumio systemd[1]: Starting Music Player Daemon... Jun 17 07:16:57 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 17 07:16:57 volumio systemd[1]: mpd.service: Succeeded. Jun 17 07:16:57 volumio systemd[1]: Stopped Music Player Daemon. Jun 17 07:16:57 volumio systemd[1]: Starting Music Player Daemon... Jun 17 07:16:57 volumio volumio[6938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 07:16:57 volumio volumio[6938]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 07:16:57 volumio volumio[6938]: info: [1750112217698] CoreMusicLibrary::Adding element Last_100 Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 07:16:57 volumio volumio[6938]: info: [1750112217706] CoreMusicLibrary::Adding element Webradio Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 07:16:57 volumio volumio[6938]: info: Initializing BBC Radios Jun 17 07:16:57 volumio sudo[7150]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 17 07:16:57 volumio sudo[7150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:57 volumio sudo[7150]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 17 07:16:57 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 07:16:58 volumio volumio[6938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 17 07:16:58 volumio volumio[6938]: info: [1750112218062] CoreMusicLibrary::Adding element RADIKO Jun 17 07:16:58 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 17 07:16:58 volumio volumio[6938]: Cannot find translation for source RADIKO Jun 17 07:16:58 volumio volumio[6938]: info: Volumio Calling Home Jun 17 07:16:58 volumio sudo[7168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Jun 17 07:16:58 volumio sudo[7168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:58 volumio systemd[1]: Starting Logitech Media Server Daemon... Jun 17 07:16:58 volumio systemd[1]: Started Logitech Media Server Daemon. Jun 17 07:16:58 volumio systemd[7175]: logitechmediaserver.service: Failed to execute command: No such file or directory Jun 17 07:16:58 volumio sudo[7168]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:58 volumio systemd[7175]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Jun 17 07:16:58 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Jun 17 07:16:58 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Jun 17 07:16:58 volumio volumio[6938]: info: CoreStateMachine::setRepeat false single undefined Jun 17 07:16:58 volumio volumio[6938]: info: CoreStateMachine::pushState Jun 17 07:16:58 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:16:58 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 07:16:58 volumio volumio[6938]: info: CoreCommandRouter::volumioPushState Jun 17 07:16:58 volumio volumio[6938]: info: CoreStateMachine::setRandom null Jun 17 07:16:58 volumio volumio[6938]: info: CoreStateMachine::pushState Jun 17 07:16:58 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:16:58 volumio volumio[6938]: info: CoreCommandRouter::volumioPushState Jun 17 07:16:58 volumio sudo[7193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 17 07:16:58 volumio sudo[7193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:58 volumio volumio[6938]: info: Volumio Network Manager: Network status updated: 1 Jun 17 07:16:58 volumio sudo[7193]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:58 volumio sudo[7195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 17 07:16:58 volumio sudo[7195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:58 volumio volumio[6938]: info: logitechmediaserver started Jun 17 07:16:58 volumio volumio[6938]: info: MPD Permissions set Jun 17 07:16:58 volumio volumio[6938]: info: MPD Permissions set Jun 17 07:16:58 volumio sudo[7195]: pam_unix(sudo:session): session closed for user root Jun 17 07:16:59 volumio volumio[6938]: info: Volumio called home Jun 17 07:16:59 volumio volumio[6938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 17 07:16:59 volumio volumio[6938]: info: VolumeController:: Volume=30 Mute =false Jun 17 07:16:59 volumio volumio[6938]: info: CoreStateMachine::pushState Jun 17 07:16:59 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::volumioPushState Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 17 07:16:59 volumio volumio[6938]: info: Discovery: adding 55e405f5-a25e-4eee-b880-50dec7a9cf3e Jun 17 07:16:59 volumio volumio[6938]: info: Discovery: Found device Volumio Jun 17 07:16:59 volumio volumio[6938]: info: CoreCommandRouter::volumioGetState Jun 17 07:16:59 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:16:59 volumio volumio[6938]: info: Starting Shairport Sync Jun 17 07:16:59 volumio volumio[6938]: info: Starting Shairport Sync Jun 17 07:16:59 volumio volumio[6938]: info: Starting Shairport Sync Jun 17 07:16:59 volumio sudo[7215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 07:16:59 volumio sudo[7215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:16:59 volumio sudo[7217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 07:16:59 volumio sudo[7217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:17:00 volumio sudo[7221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 17 07:17:00 volumio sudo[7221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:17:00 volumio volumio[6938]: info: CoreCommandRouter::volumioGetState Jun 17 07:17:00 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:17:00 volumio sudo[7223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 17 07:17:00 volumio sudo[7223]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:17:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 17 07:17:00 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 17 07:17:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 17 07:17:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 17 07:17:00 volumio sudo[7221]: pam_unix(sudo:session): session closed for user root Jun 17 07:17:00 volumio sudo[7215]: pam_unix(sudo:session): session closed for user root Jun 17 07:17:00 volumio sudo[7217]: pam_unix(sudo:session): session closed for user root Jun 17 07:17:00 volumio volumio[6938]: info: Shairport-Sync Started Jun 17 07:17:00 volumio volumio[6938]: Error adding Membership: Error: addMembership EINVAL Jun 17 07:17:00 volumio volumio[6938]: info: Shairport-Sync Started Jun 17 07:17:00 volumio volumio[6938]: info: Shairport-Sync Started Jun 17 07:17:00 volumio sudo[7223]: pam_unix(sudo:session): session closed for user root Jun 17 07:17:00 volumio volumio[6938]: info: JP_Radio::getting stations Jun 17 07:17:00 volumio volumio[6938]: info: Upmpdcli Daemon Started Jun 17 07:17:01 volumio mpd[7161]: Jun 17 07:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 17 07:17:01 volumio systemd[1]: Started Music Player Daemon. Jun 17 07:17:01 volumio sudo[7143]: pam_unix(sudo:session): session closed for user root Jun 17 07:17:01 volumio sudo[7134]: pam_unix(sudo:session): session closed for user root Jun 17 07:17:01 volumio volumio[6938]: info: Completed starting Core Plugins Jun 17 07:17:01 volumio volumio[6938]: info: ------------------------------------------- Jun 17 07:17:01 volumio volumio[6938]: info: ----- MyVolumio plugins startup ---- Jun 17 07:17:01 volumio volumio[6938]: info: ------------------------------------------- Jun 17 07:17:01 volumio volumio[6938]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 17 07:17:01 volumio volumio[6938]: error: MPD error: The expression evaluated to a falsy value: Jun 17 07:17:01 volumio volumio[6938]: assert.ok(self.idling) Jun 17 07:17:01 volumio volumio[6938]: error: The expression evaluated to a falsy value: Jun 17 07:17:01 volumio volumio[6938]: assert.ok(self.idling) Jun 17 07:17:01 volumio volumio[6938]: info: MPD running with PID7161 Jun 17 07:17:01 volumio volumio[6938]: ,establishing connection Jun 17 07:17:01 volumio volumio[6938]: error: updateQueue error: null Jun 17 07:17:01 volumio volumio[6938]: error: updateQueue error: null Jun 17 07:17:02 volumio volumio[6938]: info: Jun 17 07:17:02 volumio volumio[6938]: ---------------------------- Client requests Volume 30 Jun 17 07:17:02 volumio volumio[6938]: info: VolumeController::SetAlsaVolume30 Jun 17 07:17:02 volumio volumio[6938]: info: CoreStateMachine::pushState Jun 17 07:17:02 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:17:02 volumio volumio[6938]: info: CoreCommandRouter::volumioPushState Jun 17 07:17:03 volumio volumio[6938]: info: Jun 17 07:17:03 volumio volumio[6938]: ---------------------------- Client requests Volume NaN Jun 17 07:17:03 volumio volumio[6938]: info: VolumeController::SetAlsaVolumeNaN Jun 17 07:17:03 volumio volumio[6938]: info: CoreStateMachine::pushState Jun 17 07:17:03 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:17:03 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 07:17:03 volumio volumio[6938]: info: CoreCommandRouter::volumioPushState Jun 17 07:17:05 volumio volumio-remote-updater[742]: [2025-06-17 07:17:05] [connect] Successful connection Jun 17 07:17:05 volumio volumio-remote-updater[742]: [2025-06-17 07:17:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750112225 101 Jun 17 07:17:05 volumio volumio[6938]: 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: 2 Jun 17 07:17:05 volumio volumio[6938]: info: JP_Radio::Updating program listings Jun 17 07:17:07 volumio volumio[6938]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 17 07:17:08 volumio volumio[6938]: info: CoreCommandRouter::volumioGetState Jun 17 07:17:08 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 17 07:17:10 volumio volumio[6938]: info: Adding plugin bluetooth to MyMusic Plugins Jun 17 07:17:10 volumio volumio[6938]: info: Adding plugin multiroom to MyMusic Plugins Jun 17 07:17:10 volumio volumio[6938]: info: Adding plugin metavolumio to MyMusic Plugins Jun 17 07:17:10 volumio volumio[6938]: info: Adding plugin cd_controller to MyMusic Plugins Jun 17 07:17:10 volumio volumio[6938]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 17 07:17:10 volumio volumio[6938]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 17 07:17:10 volumio volumio[6938]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 17 07:17:12 volumio dhcpcd[803]: eth0: carrier lost Jun 17 07:17:12 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Down Jun 17 07:17:12 volumio avahi-daemon[723]: Withdrawing address record for 192.168.223.251 on eth0. Jun 17 07:17:12 volumio avahi-daemon[723]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 17 07:17:12 volumio avahi-daemon[723]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 17 07:17:12 volumio dhcpcd[803]: eth0: deleting route to 192.168.223.0/24 Jun 17 07:17:12 volumio dhcpcd[803]: eth0: deleting default route via 192.168.223.1 Jun 17 07:17:12 volumio ifplugd(eth0)[1005]: Link beat lost. Jun 17 07:17:12 volumio volumio[6938]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 17 07:17:12 volumio volumio[6938]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 17 07:17:12 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 07:17:12 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 17 07:17:12 volumio volumio[6938]: info: Starting MyVolumio Remote Streaming Endpoints Jun 17 07:17:12 volumio volumio[6938]: info: MyVolumio login type: Token Jun 17 07:17:12 volumio volumio[6938]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 17 07:17:12 volumio volumio[6938]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 17 07:17:14 volumio ntpd[1094]: Deleting interface #7 eth0, 192.168.223.251#123, interface stats: received=14, sent=14, dropped=0, active_time=21 secs Jun 17 07:17:14 volumio ntpd[1094]: 194.0.5.123 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 133.130.121.141 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 139.162.81.45 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 23.81.40.154 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 160.16.113.133 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 45.77.20.103 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 129.250.35.250 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 45.76.211.39 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 162.159.200.1 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 122.215.240.52 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 103.131.151.30 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 108.160.132.224 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 103.131.151.20 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio ntpd[1094]: 202.181.103.212 local addr 192.168.223.251 -> Jun 17 07:17:14 volumio dhcpcd[803]: eth0: carrier acquired Jun 17 07:17:14 volumio kernel: dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Jun 17 07:17:14 volumio dhcpcd[803]: eth0: IAID 91:77:13:c2 Jun 17 07:17:14 volumio dhcpcd[803]: eth0: probing address 192.168.223.251/24 Jun 17 07:17:14 volumio ifplugd(eth0)[1005]: Link beat detected. Jun 17 07:17:14 volumio dhcpcd[803]: eth0: soliciting an IPv6 router Jun 17 07:17:14 volumio volumio[6938]: info: Starting Streaming Service Transparent Proxy Jun 17 07:17:14 volumio volumio[6938]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 17 07:17:14 volumio volumio[6938]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 17 07:17:14 volumio volumio[6938]: info: Streaming services startup Jun 17 07:17:14 volumio volumio[6938]: info: Starting Streaming Daemon Jun 17 07:17:14 volumio sudo[7311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 17 07:17:14 volumio sudo[7311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 07:17:14 volumio sudo[7311]: pam_unix(sudo:session): session closed for user root Jun 17 07:17:14 volumio volumio[6938]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 17 07:17:15 volumio volumio[6938]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 17 07:17:15 volumio volumio[6938]: info: Retrying Login Due to Network Error Jun 17 07:17:15 volumio volumio[6938]: error: Cannot start Volumio Streaming Daemon Jun 17 07:17:15 volumio volumio[6938]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 17 07:17:15 volumio volumio[6938]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 17 07:17:15 volumio volumio[6938]: info: Discovery: A device disappeared from network Jun 17 07:17:15 volumio volumio[6938]: info: Discovery: Device volumio disappeared from network Jun 17 07:17:15 volumio volumio[6938]: info: Jun 17 07:17:15 volumio volumio[6938]: ---------------------------- Client requests Volume 30 Jun 17 07:17:15 volumio volumio[6938]: info: VolumeController::SetAlsaVolume30 Jun 17 07:17:15 volumio volumio[6938]: info: CoreStateMachine::pushState Jun 17 07:17:15 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:17:15 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 07:17:15 volumio volumio[6938]: info: CoreCommandRouter::volumioPushState Jun 17 07:17:16 volumio volumio[6938]: (node:6938) UnhandledPromiseRejectionWarning: RequestError: getaddrinfo EBUSY radiko.jp Jun 17 07:17:16 volumio volumio[6938]: at ClientRequest. (/data/plugins/music_service/jp_radio/node_modules/got/dist/source/core/index.js:970:111) Jun 17 07:17:16 volumio volumio[6938]: at Object.onceWrapper (events.js:520:26) Jun 17 07:17:16 volumio volumio[6938]: at ClientRequest.emit (events.js:412:35) Jun 17 07:17:16 volumio volumio[6938]: at ClientRequest.origin.emit (/data/plugins/music_service/jp_radio/node_modules/@szmarczak/http-timer/dist/source/index.js:43:20) Jun 17 07:17:16 volumio volumio[6938]: at Socket.socketErrorListener (_http_client.js:475:9) Jun 17 07:17:16 volumio volumio[6938]: at Socket.emit (events.js:400:28) Jun 17 07:17:16 volumio volumio[6938]: at emitErrorNT (internal/streams/destroy.js:106:8) Jun 17 07:17:16 volumio volumio[6938]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Jun 17 07:17:16 volumio volumio[6938]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jun 17 07:17:16 volumio volumio[6938]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) Jun 17 07:17:16 volumio volumio[6938]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 17 07:17:16 volumio volumio[6938]: (node:6938) 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: 859) Jun 17 07:17:16 volumio volumio[6938]: (node:6938) [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 17 07:17:16 volumio volumio[6938]: info: Jun 17 07:17:16 volumio volumio[6938]: ---------------------------- Client requests Volume NaN Jun 17 07:17:16 volumio volumio[6938]: info: VolumeController::SetAlsaVolumeNaN Jun 17 07:17:16 volumio volumio[6938]: info: CoreStateMachine::pushState Jun 17 07:17:16 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:17:16 volumio volumio[6938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 17 07:17:16 volumio volumio[6938]: info: CoreCommandRouter::volumioPushState Jun 17 07:17:17 volumio volumio[6938]: STREAMING PROXY: Starting server on port 3245 Jun 17 07:17:17 volumio volumio[6938]: Node JS runtime: 14 Jun 17 07:17:18 volumio volumio[6938]: info: Volumio Network Manager: Network status updated: 0 Jun 17 07:17:19 volumio dhcpcd[803]: eth0: using static address 192.168.223.251/24 Jun 17 07:17:19 volumio dhcpcd[803]: eth0: adding route to 192.168.223.0/24 Jun 17 07:17:19 volumio avahi-daemon[723]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.223.251. Jun 17 07:17:19 volumio dhcpcd[803]: eth0: adding default route via 192.168.223.1 Jun 17 07:17:19 volumio avahi-daemon[723]: New relevant interface eth0.IPv4 for mDNS. Jun 17 07:17:19 volumio avahi-daemon[723]: Registering new address record for 192.168.223.251 on eth0.IPv4. Jun 17 07:17:20 volumio volumio[6938]: info: Discovery: adding 55e405f5-a25e-4eee-b880-50dec7a9cf3e Jun 17 07:17:20 volumio volumio[6938]: info: Discovery: Found device Volumio Jun 17 07:17:20 volumio volumio[6938]: info: CoreCommandRouter::volumioGetState Jun 17 07:17:20 volumio volumio[6938]: info: CorePlayQueue::getTrack 0 Jun 17 07:17:20 volumio volumio[6938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 07:17:20 volumio volumio[6938]: Error: The value NaN is not a number Jun 17 07:17:20 volumio volumio[6938]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jun 17 07:17:20 volumio volumio[6938]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jun 17 07:17:20 volumio volumio[6938]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jun 17 07:17:20 volumio volumio[6938]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jun 17 07:17:20 volumio volumio[6938]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jun 17 07:17:20 volumio volumio[6938]: at Browser.emit (events.js:400:28) Jun 17 07:17:20 volumio volumio[6938]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jun 17 07:17:20 volumio volumio[6938]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jun 17 07:17:20 volumio volumio[6938]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jun 17 07:17:20 volumio volumio[6938]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jun 17 07:17:20 volumio volumio[6938]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jun 17 07:17:20 volumio volumio[6938]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jun 17 07:17:20 volumio volumio[6938]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jun 17 07:17:20 volumio volumio[6938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 17 07:17:21 volumio ntpd[1094]: Listen normally on 8 eth0 192.168.223.251:123 Jun 17 07:17:21 volumio ntpd[1094]: new interface(s) found: waking up resolver Jun 17 07:17:21 volumio sudo[7373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-17 07:16 Jun 17 07:17:21 volumio sudo[7373]: 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"