-- Logs begin at Mon 2025-05-05 09:03:02 JST, end at Mon 2025-05-05 11:31:44 JST. -- May 05 11:31:31 volumio593 wpa_supplicant[917]: wlan0: CTRL-EVENT-DISCONNECTED bssid=08:10:86:ef:1b:7f reason=3 May 05 11:31:31 volumio593 dhcpcd[963]: wlan0: carrier lost May 05 11:31:31 volumio593 wpa_supplicant[917]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 05 11:31:31 volumio593 wpa_supplicant[917]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=JP May 05 11:31:31 volumio593 avahi-daemon[599]: Withdrawing address record for 192.168.1.79 on wlan0. May 05 11:31:31 volumio593 avahi-daemon[599]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.79. May 05 11:31:31 volumio593 dhcpcd[963]: wlan0: deleting route to 192.168.1.0/24 May 05 11:31:31 volumio593 avahi-daemon[599]: Interface wlan0.IPv4 no longer relevant for mDNS. May 05 11:31:31 volumio593 dhcpcd[963]: eth0: deleting route to 192.168.1.0/24 May 05 11:31:31 volumio593 dhcpcd[963]: wlan0: deleting default route via 192.168.1.1 May 05 11:31:31 volumio593 dhcpcd[963]: eth0: deleting default route via 192.168.1.1 May 05 11:31:31 volumio593 dhcpcd[884]: eth0: pid 963 deleted route to 192.168.1.0/24 May 05 11:31:31 volumio593 dhcpcd[884]: eth0: pid 963 deleted default route via 192.168.1.1 May 05 11:31:31 volumio593 volumio[1066]: info: Discovery: A device disappeared from network May 05 11:31:31 volumio593 volumio[1066]: info: Discovery: Device volumio593 disappeared from network May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.125560, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 11:31:32 volumio593 nmbd[740]: Packet send failed to 192.168.1.9(137) ERRNO=Network is unreachable May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.125895, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) May 05 11:31:32 volumio593 nmbd[740]: reply_netbios_packet: send_packet to IP 192.168.1.9 port 137 failed May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.126200, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 11:31:32 volumio593 nmbd[740]: Packet send failed to 192.168.1.9(137) ERRNO=Network is unreachable May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.126302, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) May 05 11:31:32 volumio593 nmbd[740]: reply_netbios_packet: send_packet to IP 192.168.1.9 port 137 failed May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.126532, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 11:31:32 volumio593 nmbd[740]: Packet send failed to 192.168.1.9(137) ERRNO=Network is unreachable May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.126618, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) May 05 11:31:32 volumio593 nmbd[740]: reply_netbios_packet: send_packet to IP 192.168.1.9 port 137 failed May 05 11:31:32 volumio593 ntpd[791]: Deleting interface #8 wlan0, 192.168.1.79#123, interface stats: received=0, sent=0, dropped=0, active_time=1853 secs May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.890121, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 11:31:32 volumio593 nmbd[740]: Packet send failed to 192.168.1.9(137) ERRNO=Network is unreachable May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.890333, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) May 05 11:31:32 volumio593 nmbd[740]: reply_netbios_packet: send_packet to IP 192.168.1.9 port 137 failed May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.890595, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 11:31:32 volumio593 nmbd[740]: Packet send failed to 192.168.1.9(137) ERRNO=Network is unreachable May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.890681, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) May 05 11:31:32 volumio593 nmbd[740]: reply_netbios_packet: send_packet to IP 192.168.1.9 port 137 failed May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.890861, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 11:31:32 volumio593 nmbd[740]: Packet send failed to 192.168.1.9(137) ERRNO=Network is unreachable May 05 11:31:32 volumio593 nmbd[740]: [2025/05/05 11:31:32.890941, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) May 05 11:31:32 volumio593 nmbd[740]: reply_netbios_packet: send_packet to IP 192.168.1.9 port 137 failed May 05 11:31:33 volumio593 volumio[1066]: info: Volumio Network Manager: Network status updated: 1 May 05 11:31:33 volumio593 nmbd[740]: [2025/05/05 11:31:33.647543, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 11:31:33 volumio593 nmbd[740]: Packet send failed to 192.168.1.9(137) ERRNO=Network is unreachable May 05 11:31:33 volumio593 nmbd[740]: [2025/05/05 11:31:33.647759, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) May 05 11:31:33 volumio593 nmbd[740]: reply_netbios_packet: send_packet to IP 192.168.1.9 port 137 failed May 05 11:31:33 volumio593 nmbd[740]: [2025/05/05 11:31:33.648019, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 11:31:33 volumio593 nmbd[740]: Packet send failed to 192.168.1.9(137) ERRNO=Network is unreachable May 05 11:31:33 volumio593 nmbd[740]: [2025/05/05 11:31:33.648111, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) May 05 11:31:33 volumio593 nmbd[740]: reply_netbios_packet: send_packet to IP 192.168.1.9 port 137 failed May 05 11:31:33 volumio593 nmbd[740]: [2025/05/05 11:31:33.648299, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 05 11:31:33 volumio593 nmbd[740]: Packet send failed to 192.168.1.9(137) ERRNO=Network is unreachable May 05 11:31:33 volumio593 nmbd[740]: [2025/05/05 11:31:33.648378, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) May 05 11:31:33 volumio593 nmbd[740]: reply_netbios_packet: send_packet to IP 192.168.1.9 port 137 failed May 05 11:31:34 volumio593 volumio[1066]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 11:31:34 volumio593 volumio[1066]: Error: send ENETUNREACH 255.255.255.255:3483 May 05 11:31:34 volumio593 volumio[1066]: at doSend (dgram.js:692:16) May 05 11:31:34 volumio593 volumio[1066]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 05 11:31:34 volumio593 volumio[1066]: at afterDns (dgram.js:638:5) May 05 11:31:34 volumio593 volumio[1066]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 05 11:31:34 volumio593 volumio[1066]: errno: -101, May 05 11:31:34 volumio593 volumio[1066]: code: 'ENETUNREACH', May 05 11:31:34 volumio593 volumio[1066]: syscall: 'send', May 05 11:31:34 volumio593 volumio[1066]: address: '255.255.255.255', May 05 11:31:34 volumio593 volumio[1066]: port: 3483 May 05 11:31:34 volumio593 volumio[1066]: } May 05 11:31:34 volumio593 volumio[1066]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 11:31:34 volumio593 sudo[6990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 11:30 May 05 11:31:34 volumio593 sudo[6990]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:35 volumio593 sudo[6990]: pam_unix(sudo:session): session closed for user root May 05 11:31:35 volumio593 volumio-remote-updater[601]: [2025-05-05 11:31:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 05 11:31:35 volumio593 volumio-remote-updater[601]: [2025-05-05 11:31:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 05 11:31:35 volumio593 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 05 11:31:35 volumio593 ntfs-3g[1135]: Unmounting /dev/sda1 (SP UFD U3) May 05 11:31:35 volumio593 systemd[1]: media-SP_UFD_U3.mount: Succeeded. May 05 11:31:35 volumio593 systemd[1]: volumio.service: Failed with result 'exit-code'. May 05 11:31:35 volumio593 systemd[1]: Started dynamicswap service. May 05 11:31:35 volumio593 systemd[1]: dynamicswap.service: Succeeded. May 05 11:31:35 volumio593 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 05 11:31:35 volumio593 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 05 11:31:35 volumio593 systemd[1]: Stopped Volumio Backend Module. May 05 11:31:35 volumio593 systemd[1]: Started Volumio Backend Module. May 05 11:31:35 volumio593 systemd[1]: Started dynamicswap service. May 05 11:31:35 volumio593 systemd[1]: dynamicswap.service: Succeeded. May 05 11:31:35 volumio593 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 May 05 11:31:35 volumio593 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 05 11:31:35 volumio593 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 05 11:31:35 volumio593 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 05 11:31:36 volumio593 volumio[7007]: info: ------------------------------------------- May 05 11:31:36 volumio593 volumio[7007]: info: ----- Volumio3 ---- May 05 11:31:36 volumio593 volumio[7007]: info: ------------------------------------------- May 05 11:31:36 volumio593 volumio[7007]: info: ----- System startup ---- May 05 11:31:36 volumio593 volumio[7007]: info: ------------------------------------------- May 05 11:31:37 volumio593 volumio[7007]: info: MYVOLUMIO Environment detected May 05 11:31:37 volumio593 volumio[7007]: info: Plugin folders cleanup May 05 11:31:37 volumio593 volumio[7007]: info: Scanning into folder /volumio/app/plugins/ May 05 11:31:37 volumio593 volumio[7007]: info: Scanning category audio_interface May 05 11:31:37 volumio593 volumio[7007]: info: Scanning category miscellanea May 05 11:31:37 volumio593 volumio[7007]: info: Scanning category music_service May 05 11:31:37 volumio593 volumio[7007]: info: Scanning category plugins.json May 05 11:31:37 volumio593 volumio[7007]: info: Scanning category system_controller May 05 11:31:37 volumio593 volumio[7007]: info: Scanning category user_interface May 05 11:31:37 volumio593 volumio[7007]: info: Scanning into folder /data/plugins/ May 05 11:31:37 volumio593 volumio[7007]: info: Scanning category music_service May 05 11:31:37 volumio593 volumio[7007]: info: Plugin folders cleanup completed May 05 11:31:37 volumio593 volumio[7007]: info: ------------------------------------------- May 05 11:31:37 volumio593 volumio[7007]: info: ----- Core plugins startup ---- May 05 11:31:37 volumio593 volumio[7007]: info: ------------------------------------------- May 05 11:31:37 volumio593 volumio[7007]: info: Loading plugins from folder /volumio/app/plugins/ May 05 11:31:37 volumio593 volumio[7007]: info: Adding plugin upnp to MyMusic Plugins May 05 11:31:37 volumio593 volumio[7007]: info: Adding plugin airplay_emulation to MyMusic Plugins May 05 11:31:37 volumio593 volumio[7007]: info: Adding plugin upnp_browser to MyMusic Plugins May 05 11:31:37 volumio593 volumio[7007]: info: Loading plugins from folder /data/plugins/ May 05 11:31:37 volumio593 volumio[7007]: info: Loading plugin "system"... May 05 11:31:37 volumio593 wpa_supplicant[917]: wlan0: Trying to associate with SSID 'aterm-29475b-a' May 05 11:31:37 volumio593 volumio[7007]: info: Loading plugin "appearance"... May 05 11:31:37 volumio593 dhcpcd[963]: wlan0: carrier acquired May 05 11:31:37 volumio593 wpa_supplicant[917]: wlan0: Associated with 08:10:86:ef:1b:7f May 05 11:31:37 volumio593 wpa_supplicant[917]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:10:86:ef:1b:7f completed [id=0 id_str=] May 05 11:31:37 volumio593 wpa_supplicant[917]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 05 11:31:37 volumio593 wpa_supplicant[917]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP May 05 11:31:37 volumio593 dhcpcd[963]: wlan0: IAID eb:d7:e1:13 May 05 11:31:37 volumio593 dhcpcd[963]: wlan0: carrier lost May 05 11:31:37 volumio593 dhcpcd[963]: wlan0: carrier acquired May 05 11:31:37 volumio593 dhcpcd[963]: wlan0: IAID eb:d7:e1:13 May 05 11:31:38 volumio593 dhcpcd[963]: wlan0: soliciting an IPv6 router May 05 11:31:38 volumio593 volumio[7007]: info: Loading plugin "network"... May 05 11:31:38 volumio593 volumio[7007]: info: Refreshing Cached IP Addresses May 05 11:31:38 volumio593 sudo[7054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 11:31:38 volumio593 sudo[7054]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:38 volumio593 sudo[7054]: pam_unix(sudo:session): session closed for user root May 05 11:31:38 volumio593 sudo[7056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 11:31:38 volumio593 sudo[7056]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:38 volumio593 sudo[7056]: pam_unix(sudo:session): session closed for user root May 05 11:31:38 volumio593 volumio[7007]: info: Loading plugin "services"... May 05 11:31:38 volumio593 volumio[7007]: info: Loading plugin "alsa_controller"... May 05 11:31:38 volumio593 sudo[7069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 05 11:31:38 volumio593 sudo[7069]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:38 volumio593 dhcpcd[963]: wlan0: rebinding lease of 192.168.1.79 May 05 11:31:38 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 11:31:38 volumio593 volumio[7007]: info: Loading plugin "wizard"... May 05 11:31:38 volumio593 volumio[7007]: info: Loading plugin "networkfs"... May 05 11:31:38 volumio593 volumio[7007]: info: Starting Udev Watcher for removable devices May 05 11:31:38 volumio593 volumio[7007]: info: Ignoring mount for partition: boot May 05 11:31:38 volumio593 volumio[7007]: info: Ignoring mount for partition: volumio May 05 11:31:38 volumio593 volumio[7007]: info: Ignoring mount for partition: volumio_data May 05 11:31:38 volumio593 dhcpcd[963]: wlan0: probing address 192.168.1.79/24 May 05 11:31:38 volumio593 volumio[7007]: info: Mounting Device SP_UFD_U3 May 05 11:31:38 volumio593 sudo[7085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/SP_UFD_U3 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 05 11:31:38 volumio593 sudo[7085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:39 volumio593 ntfs-3g[7088]: Version 2017.3.23AR.3 integrated FUSE 28 May 05 11:31:39 volumio593 ntfs-3g[7088]: Mounted /dev/sda1 (Read-Write, label "SP UFD U3", NTFS 3.1) May 05 11:31:39 volumio593 ntfs-3g[7088]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 May 05 11:31:39 volumio593 ntfs-3g[7088]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 May 05 11:31:39 volumio593 ntfs-3g[7088]: Global ownership and permissions enforced, configuration type 7 May 05 11:31:39 volumio593 sudo[7085]: pam_unix(sudo:session): session closed for user root May 05 11:31:39 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "volumio_command_line_client"... May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "upnp"... May 05 11:31:39 volumio593 volumio[7007]: info: [1746412299102] Starting Upmpd Daemon May 05 11:31:39 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "my_music"... May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "mpd"... May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "upnp_browser"... May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "alarm-clock"... May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "airplay_emulation"... May 05 11:31:39 volumio593 volumio[7007]: info: Starting Shairport Sync May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "last_100"... May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "webradio"... May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "i2s_dacs"... May 05 11:31:39 volumio593 volumio[7007]: info: Loading plugin "volumiodiscovery"... May 05 11:31:39 volumio593 volumio[7007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 11:31:39 volumio593 volumio[7007]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 11:31:39 volumio593 volumio[7007]: *** WARNING *** For more information see May 05 11:31:39 volumio593 node[7007]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 11:31:39 volumio593 volumio[7007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 11:31:39 volumio593 volumio[7007]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 11:31:39 volumio593 volumio[7007]: *** WARNING *** For more information see May 05 11:31:39 volumio593 node[7007]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 11:31:39 volumio593 node[7007]: *** WARNING *** For more information see May 05 11:31:39 volumio593 node[7007]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 11:31:39 volumio593 node[7007]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 11:31:39 volumio593 node[7007]: *** WARNING *** For more information see May 05 11:31:39 volumio593 volumio[7007]: info: Applying required configuration parameters for plugin volumiodiscovery May 05 11:31:39 volumio593 volumio[7007]: info: Discovery: Started advertising with name: Volumio593 May 05 11:31:40 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 11:31:40 volumio593 volumio[7007]: info: Loading plugin "squeezelite_mc"... May 05 11:31:40 volumio593 volumio-remote-updater[601]: [2025-05-05 11:31:40] [connect] Successful connection May 05 11:31:40 volumio593 volumio[7007]: info: Loading plugin "outputs"... May 05 11:31:40 volumio593 volumio[7007]: info: Loading plugin "albumart"... May 05 11:31:40 volumio593 volumio[7007]: info: Plugin example_plugin is not enabled May 05 11:31:40 volumio593 volumio[7007]: info: Loading plugin "inputs"... May 05 11:31:40 volumio593 volumio[7007]: info: Loading plugin "updater_comm"... May 05 11:31:40 volumio593 volumio[7007]: info: Plugin mpdemulation is not enabled May 05 11:31:40 volumio593 volumio[7007]: info: Loading plugin "rest_api"... May 05 11:31:41 volumio593 volumio[7007]: info: Loading plugin "websocket"... May 05 11:31:41 volumio593 volumio[7007]: info: Starting Socket.io Server version 2.3.0 May 05 11:31:41 volumio593 volumio[7007]: info: Loading plugin "lms"... May 05 11:31:41 volumio593 volumio[7007]: info: Loading i18n strings for locale ja May 05 11:31:41 volumio593 volumio[7007]: Forking 3 albumart workers May 05 11:31:41 volumio593 volumio[7007]: Updating browse sources language May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::initPlayerControls May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: Express server listening on port 3000 May 05 11:31:41 volumio593 volumio[7007]: [Metrics] WebUI: 4s 995.35ms May 05 11:31:41 volumio593 volumio[7007]: info: CoreStateMachine::resetVolumioState May 05 11:31:41 volumio593 volumio[7007]: info: CoreStateMachine::getcurrentVolume May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::volumioRetrievevolume May 05 11:31:41 volumio593 volumio[7007]: info: Volumio Network Manager: Network status updated: 1 May 05 11:31:41 volumio593 volumio-remote-updater[601]: [2025-05-05 11:31:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746412300 101 May 05 11:31:41 volumio593 volumio[7007]: 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 May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:31:41 volumio593 volumio[7007]: info: Executing start script for DAC IQaudIO DAC Plus May 05 11:31:41 volumio593 volumio[7007]: info: Reloading queue from file May 05 11:31:41 volumio593 volumio[7007]: info: Setting Device type: Raspberry PI May 05 11:31:41 volumio593 volumio[7007]: info: CoreStateMachine::setRepeat null single undefined May 05 11:31:41 volumio593 volumio[7007]: info: CoreStateMachine::pushState May 05 11:31:41 volumio593 volumio[7007]: info: CorePlayQueue::getTrack 0 May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::volumioPushState May 05 11:31:41 volumio593 volumio[7007]: info: CoreStateMachine::setRandom null May 05 11:31:41 volumio593 volumio[7007]: info: CoreStateMachine::pushState May 05 11:31:41 volumio593 volumio[7007]: info: CorePlayQueue::getTrack 0 May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::volumioPushState May 05 11:31:41 volumio593 volumio[7007]: info: VolumeController:: Volume=100 Mute =false May 05 11:31:41 volumio593 volumio[7007]: info: CoreStateMachine::pushState May 05 11:31:41 volumio593 volumio[7007]: info: CorePlayQueue::getTrack 0 May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::volumioPushState May 05 11:31:41 volumio593 volumio[7007]: info: CoreStateMachine::updateTrackBlock May 05 11:31:41 volumio593 volumio[7007]: info: CorePlayQueue::getTrackBlock May 05 11:31:41 volumio593 volumio[7007]: info: CoreCommandRouter::volumioRetrievevolume May 05 11:31:41 volumio593 volumio[7007]: info: DAC script executed May 05 11:31:41 volumio593 volumio[7007]: info: Completed loading Core Plugins May 05 11:31:41 volumio593 volumio[7007]: info: Preparing to generate the ALSA configuration file May 05 11:31:41 volumio593 volumio[7007]: info: Asound.conf file unchanged, so no further update is needed May 05 11:31:41 volumio593 volumio[7007]: info: Output device has changed, restarting MPD May 05 11:31:42 volumio593 volumio[7007]: info: Output device has changed, restarting Shairport Sync May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:31:42 volumio593 sudo[7069]: pam_unix(sudo:session): session closed for user root May 05 11:31:42 volumio593 sudo[7137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 11:31:42 volumio593 sudo[7137]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:42 volumio593 sudo[7137]: pam_unix(sudo:session): session closed for user root May 05 11:31:42 volumio593 sudo[7140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 11:31:42 volumio593 sudo[7140]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:42 volumio593 volumio[7007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 11:31:42 volumio593 volumio[7007]: info: ___________ START PLUGINS ___________ May 05 11:31:42 volumio593 volumio[7007]: info: ControllerMpd::onStart: Initializing MPD May 05 11:31:42 volumio593 volumio[7007]: info: Creating MPD Configuration file May 05 11:31:42 volumio593 systemd[1]: Stopping Music Player Daemon... May 05 11:31:42 volumio593 systemd[1]: mpd.service: Succeeded. May 05 11:31:42 volumio593 systemd[1]: Stopped Music Player Daemon. May 05 11:31:42 volumio593 sudo[7146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:31:42 volumio593 sudo[7146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:42 volumio593 sudo[7146]: pam_unix(sudo:session): session closed for user root May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 11:31:42 volumio593 volumio[7007]: info: [1746412302200] CoreMusicLibrary::Adding element DLNAサーバー May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 11:31:42 volumio593 sudo[7149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 11:31:42 volumio593 volumio[7007]: Starting albumart workers May 05 11:31:42 volumio593 systemd[1]: Starting Music Player Daemon... May 05 11:31:42 volumio593 sudo[7149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:31:42 volumio593 volumio[7007]: Starting albumart workers May 05 11:31:42 volumio593 volumio[7007]: Starting albumart workers May 05 11:31:42 volumio593 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 05 11:31:42 volumio593 systemd[1]: mpd.service: Succeeded. May 05 11:31:42 volumio593 systemd[1]: Stopped Music Player Daemon. May 05 11:31:42 volumio593 volumio[7007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:42 volumio593 systemd[1]: Starting Music Player Daemon... May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:31:42 volumio593 volumio[7007]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 11:31:42 volumio593 volumio[7007]: info: [1746412302383] CoreMusicLibrary::Adding element Last_100 May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 11:31:42 volumio593 volumio[7007]: info: [1746412302386] CoreMusicLibrary::Adding element Webradio May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 11:31:42 volumio593 volumio[7007]: info: Initializing BBC Radios May 05 11:31:42 volumio593 sudo[7157]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 05 11:31:42 volumio593 sudo[7157]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:42 volumio593 sudo[7157]: pam_unix(sudo:session): session closed for user root May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:31:42 volumio593 volumio[7007]: info: [squeezelite_mc] Starting proxy server... May 05 11:31:42 volumio593 volumio[7007]: info: Volumio Calling Home May 05 11:31:42 volumio593 sudo[7174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver May 05 11:31:42 volumio593 sudo[7174]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 11:31:42 volumio593 systemd[1]: Starting Logitech Media Server Daemon... May 05 11:31:42 volumio593 systemd[1]: Started Logitech Media Server Daemon. May 05 11:31:42 volumio593 sudo[7174]: pam_unix(sudo:session): session closed for user root May 05 11:31:42 volumio593 systemd[7181]: logitechmediaserver.service: Failed to execute command: No such file or directory May 05 11:31:42 volumio593 systemd[7181]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory May 05 11:31:42 volumio593 systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC May 05 11:31:42 volumio593 systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. May 05 11:31:42 volumio593 volumio[7007]: info: [squeezelite_mc] Proxy server started on port 43767 May 05 11:31:42 volumio593 volumio[7007]: info: CoreCommandRouter::volumioRetrievevolume May 05 11:31:42 volumio593 volumio[7007]: info: Discovery: adding 2cbdebb6-6b21-498c-9e3d-67cd1f0be9d4 May 05 11:31:43 volumio593 volumio[7007]: info: Discovery: Found device Volumio593 May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::volumioGetState May 05 11:31:43 volumio593 volumio[7007]: info: CorePlayQueue::getTrack 0 May 05 11:31:43 volumio593 volumio[7007]: info: logitechmediaserver started May 05 11:31:43 volumio593 volumio[7007]: info: MPD Permissions set May 05 11:31:43 volumio593 volumio[7007]: info: MPD Permissions set May 05 11:31:43 volumio593 volumio[7007]: info: VolumeController:: Volume=100 Mute =false May 05 11:31:43 volumio593 volumio[7007]: info: CoreStateMachine::pushState May 05 11:31:43 volumio593 volumio[7007]: info: CorePlayQueue::getTrack 0 May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::volumioPushState May 05 11:31:43 volumio593 volumio[7007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: VolumeController:: Volume=100 Mute =false May 05 11:31:43 volumio593 volumio[7007]: info: CoreStateMachine::pushState May 05 11:31:43 volumio593 volumio[7007]: info: CorePlayQueue::getTrack 0 May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::volumioPushState May 05 11:31:43 volumio593 volumio[7007]: info: [squeezelite_mc] Server discovery started May 05 11:31:43 volumio593 volumio[7007]: info: [squeezelite_mc] Player finder started May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 11:31:43 volumio593 volumio[7007]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 05 11:31:43 volumio593 volumio[7007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 11:31:43 volumio593 volumio[7007]: Error: send ENETUNREACH 255.255.255.255:3483 May 05 11:31:43 volumio593 volumio[7007]: at doSend (dgram.js:692:16) May 05 11:31:43 volumio593 volumio[7007]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 05 11:31:43 volumio593 volumio[7007]: at afterDns (dgram.js:638:5) May 05 11:31:43 volumio593 volumio[7007]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 05 11:31:43 volumio593 volumio[7007]: errno: -101, May 05 11:31:43 volumio593 volumio[7007]: code: 'ENETUNREACH', May 05 11:31:43 volumio593 volumio[7007]: syscall: 'send', May 05 11:31:43 volumio593 volumio[7007]: address: '255.255.255.255', May 05 11:31:43 volumio593 volumio[7007]: port: 3483 May 05 11:31:43 volumio593 volumio[7007]: } May 05 11:31:43 volumio593 volumio[7007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 11:31:43 volumio593 dhcpcd[963]: wlan0: leased 192.168.1.79 for 14400 seconds May 05 11:31:43 volumio593 avahi-daemon[599]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.79. May 05 11:31:43 volumio593 avahi-daemon[599]: New relevant interface wlan0.IPv4 for mDNS. May 05 11:31:43 volumio593 avahi-daemon[599]: Registering new address record for 192.168.1.79 on wlan0.IPv4. May 05 11:31:43 volumio593 dhcpcd[963]: wlan0: adding route to 192.168.1.0/24 May 05 11:31:43 volumio593 dhcpcd[963]: wlan0: adding default route via 192.168.1.1 May 05 11:31:44 volumio593 sudo[7248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 11:30 May 05 11:31:44 volumio593 sudo[7248]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"