-- Logs begin at Sun 2021-01-31 14:28:09 UTC, end at Tue 2025-09-16 14:31:04 UTC. -- Sep 16 14:30:00 volumio snapclient[3369]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:00 volumio snapclient[3369]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:00 volumio netplugd[873]: eth0: state INACTIVE flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00011043 UP,BROADCAST,RUNNING,MULTICAST,10000 Sep 16 14:30:00 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:00 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:00 volumio netplugd[873]: eth0: state WAIT_IN flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:00 volumio netplugd[873]: eth0: state INACTIVE flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:00 volumio netplugd[873]: eth0: state INACTIVE flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:00 volumio netplugd[873]: eth0: state INACTIVE flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:00 volumio netplugd[873]: eth0: state INACTIVE flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:00 volumio netplugd[3527]: /etc/netplug/netplug eth0 in -> pid 3527 Sep 16 14:30:00 volumio dhcpcd[622]: eth0: carrier acquired Sep 16 14:30:00 volumio netplugd[3528]: /etc/netplug/netplug eth0 in -> pid 3528 Sep 16 14:30:00 volumio netplugd[3526]: /etc/netplug/netplug eth0 in -> pid 3526 Sep 16 14:30:00 volumio netplugd[3525]: /etc/netplug/netplug eth0 in -> pid 3525 Sep 16 14:30:00 volumio netplugd[3529]: /etc/netplug/netplug eth0 in -> pid 3529 Sep 16 14:30:00 volumio netplugd[873]: eth0: state INNING pid 3527 exited status 0 Sep 16 14:30:00 volumio dhcpcd[622]: eth0: IAID eb:57:e1:ca Sep 16 14:30:00 volumio netplugd[873]: eth0: state INNING pid 3526 exited status 0 Sep 16 14:30:00 volumio netplugd[873]: eth0: state INNING pid 3528 exited status 0 Sep 16 14:30:00 volumio netplugd[873]: eth0: state INNING pid 3525 exited status 0 Sep 16 14:30:00 volumio netplugd[873]: eth0: state INNING pid 3529 exited status 0 Sep 16 14:30:00 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Sep 16 14:30:00 volumio dhcpcd[622]: eth0: rebinding lease of 192.168.2.231 Sep 16 14:30:00 volumio volumio[2810]: info: CoreStateMachine::setRepeat true single undefined Sep 16 14:30:00 volumio volumio[2810]: info: CoreStateMachine::pushState Sep 16 14:30:00 volumio volumio[2810]: info: CoreStateMachine::getState Sep 16 14:30:00 volumio volumio[2810]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:00 volumio volumio[2810]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:00 volumio volumio[2810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:00 volumio volumio[2810]: info: interfaceApi::pushState Sep 16 14:30:00 volumio volumio[2810]: info: CoreStateMachine::setRandom true Sep 16 14:30:00 volumio volumio[2810]: info: CoreStateMachine::pushState Sep 16 14:30:00 volumio volumio[2810]: info: CoreStateMachine::getState Sep 16 14:30:00 volumio volumio[2810]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:00 volumio volumio[2810]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:00 volumio volumio[2810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:00 volumio volumio[2810]: info: interfaceApi::pushState Sep 16 14:30:00 volumio volumio[2810]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:30:00 volumio wireless.js[3518]: WIRELESS DAEMON: start Sep 16 14:30:00 volumio wireless.js[3518]: WIRELESS: Loaded configuration Sep 16 14:30:00 volumio wireless.js[3518]: Cleaning previous... Sep 16 14:30:00 volumio volumio-remote-updater[604]: [2025-09-16 14:30:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1758032999 101 Sep 16 14:30:00 volumio volumio[2810]: null Sep 16 14:30:00 volumio volumio[2810]: info: VolumeController::Volume 10 Sep 16 14:30:00 volumio volumio[2810]: info: CoreStateMachine::pushState Sep 16 14:30:00 volumio volumio[2810]: info: CoreStateMachine::getState Sep 16 14:30:00 volumio volumio[2810]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:00 volumio volumio[2810]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:00 volumio systemd[1]: Stopped hotspot.service. Sep 16 14:30:00 volumio volumio[2810]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:00 volumio volumio[2810]: info: interfaceApi::pushState Sep 16 14:30:00 volumio volumio[2810]: info: Setting volume on startup at 10 Sep 16 14:30:00 volumio volumio[2810]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:30:01 volumio volumio[2810]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:30:01 volumio volumio[2810]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:30:01 volumio sudo[3537]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 16 14:30:01 volumio sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:01 volumio sudo[3537]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:01 volumio sudo[3545]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 16 14:30:01 volumio snapclient[3369]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:01 volumio snapclient[3369]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:01 volumio sudo[3545]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:01 volumio sudo[3545]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:01 volumio volumio[2810]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 14:30:01 volumio volumio[2810]: { Error: addMembership ENODEV Sep 16 14:30:01 volumio volumio[2810]: at exports._errnoException (util.js:1018:11) Sep 16 14:30:01 volumio volumio[2810]: at Socket.addMembership (dgram.js:506:11) Sep 16 14:30:01 volumio volumio[2810]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:251:16) Sep 16 14:30:01 volumio volumio[2810]: at ontimeout (timers.js:386:14) Sep 16 14:30:01 volumio volumio[2810]: at tryOnTimeout (timers.js:250:5) Sep 16 14:30:01 volumio volumio[2810]: at Timer.listOnTimeout (timers.js:214:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Sep 16 14:30:01 volumio volumio[2810]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 14:30:01 volumio wireless.js[3518]: Stopped aP Sep 16 14:30:01 volumio wireless.js[3518]: Wireless Networking DISABLED, not starting wireless flow Sep 16 14:30:01 volumio systemd[1]: Started Wireless Services. Sep 16 14:30:01 volumio netplugd[873]: eth0: state PROBING_UP pid 3502 exited status 0 Sep 16 14:30:01 volumio netplugd[3565]: /etc/netplug/netplug eth0 in -> pid 3565 Sep 16 14:30:01 volumio dhcpcd[622]: eth0: soliciting an IPv6 router Sep 16 14:30:01 volumio netplugd[873]: eth0: state INNING pid 3565 exited status 0 Sep 16 14:30:02 volumio snapclient[3369]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:02 volumio snapclient[3369]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:02 volumio sudo[3567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 14:29 Sep 16 14:30:02 volumio sudo[3567]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:03 volumio snapclient[3369]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:03 volumio snapclient[3369]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:03 volumio sudo[3567]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:03 volumio volumio-remote-updater[604]: [2025-09-16 14:30:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 16 14:30:03 volumio volumio-remote-updater[604]: [2025-09-16 14:30:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 16 14:30:03 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Sep 16 14:30:03 volumio systemd[1]: Unit volumio.service entered failed state. Sep 16 14:30:03 volumio systemd[1]: Starting dynamicswap service... Sep 16 14:30:03 volumio systemd[1]: Started dynamicswap service. Sep 16 14:30:03 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Sep 16 14:30:03 volumio systemd[1]: Starting dynamicswap service... Sep 16 14:30:03 volumio systemd[1]: Started dynamicswap service. Sep 16 14:30:03 volumio systemd[1]: Stopping Volumio Backend Module... Sep 16 14:30:03 volumio systemd[1]: Starting Volumio Backend Module... Sep 16 14:30:03 volumio systemd[1]: Started Volumio Backend Module. Sep 16 14:30:04 volumio snapclient[3369]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:04 volumio snapclient[3369]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:05 volumio snapclient[3369]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:05 volumio snapclient[3369]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:06 volumio dhcpcd[622]: eth0: leased 192.168.2.231 for 7200 seconds Sep 16 14:30:06 volumio avahi-daemon[617]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.231. Sep 16 14:30:06 volumio dhcpcd[622]: eth0: adding route to 192.168.2.0/24 Sep 16 14:30:06 volumio avahi-daemon[617]: New relevant interface eth0.IPv4 for mDNS. Sep 16 14:30:06 volumio dhcpcd[622]: eth0: adding default route via 192.168.2.90 Sep 16 14:30:06 volumio avahi-daemon[617]: Registering new address record for 192.168.2.231 on eth0.IPv4. Sep 16 14:30:06 volumio snapclient[3369]: Connected to 192.168.2.231 Sep 16 14:30:06 volumio snapserver[3095]: StreamServer::NewConnection: ::ffff:192.168.2.231 Sep 16 14:30:06 volumio snapserver[3095]: Session inactive. Removing Sep 16 14:30:07 volumio ntpd[800]: Listen normally on 7 eth0 192.168.2.231 UDP 123 Sep 16 14:30:07 volumio ntpd[800]: peers refreshed Sep 16 14:30:08 volumio volumio[3591]: info: ------------------------------------------- Sep 16 14:30:08 volumio volumio[3591]: info: ----- Volumio2 ---- Sep 16 14:30:08 volumio volumio[3591]: info: ------------------------------------------- Sep 16 14:30:08 volumio volumio[3591]: info: ----- System startup ---- Sep 16 14:30:08 volumio volumio[3591]: info: ------------------------------------------- Sep 16 14:30:08 volumio volumio[3591]: info: Plugin folders cleanup Sep 16 14:30:08 volumio volumio[3591]: info: Scanning into folder /volumio/app/plugins/ Sep 16 14:30:08 volumio volumio[3591]: info: Scanning category audio_interface Sep 16 14:30:08 volumio volumio[3591]: info: Scanning category miscellanea Sep 16 14:30:08 volumio volumio[3591]: info: Scanning category music_service Sep 16 14:30:08 volumio volumio[3591]: info: Scanning category plugins.json Sep 16 14:30:08 volumio volumio[3591]: info: Scanning category system_controller Sep 16 14:30:08 volumio volumio[3591]: info: Scanning category user_interface Sep 16 14:30:08 volumio volumio[3591]: info: Scanning into folder /data/plugins/ Sep 16 14:30:08 volumio volumio[3591]: info: Scanning category miscellanea Sep 16 14:30:08 volumio volumio[3591]: info: Plugin folders cleanup completed Sep 16 14:30:08 volumio volumio[3591]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 14:30:08 volumio volumio[3591]: info: Loading plugins from folder /data/plugins/ Sep 16 14:30:08 volumio volumio[3591]: info: Loading plugin "system"... Sep 16 14:30:08 volumio volumio[3591]: info: Loading plugin "appearance"... Sep 16 14:30:08 volumio volumio-remote-updater[604]: [2025-09-16 14:30:08] [connect] Successful connection Sep 16 14:30:10 volumio volumio[3591]: info: Loading plugin "network"... Sep 16 14:30:10 volumio volumio[3591]: info: Loading plugin "services"... Sep 16 14:30:10 volumio volumio[3591]: info: Loading plugin "alsa_controller"... Sep 16 14:30:11 volumio volumio[3591]: info: Loading plugin "wizard"... Sep 16 14:30:11 volumio volumio[3591]: info: Loading plugin "volumio_command_line_client"... Sep 16 14:30:11 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:11 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:11 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:11 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:11 volumio volumio[3591]: info: Loading plugin "upnp"... Sep 16 14:30:11 volumio volumio[3591]: info: [1758033011181] Starting Upmpd Daemon Sep 16 14:30:11 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 14:30:11 volumio volumio[3591]: info: Loading plugin "mpd"... Sep 16 14:30:11 volumio volumio[3591]: info: Loading plugin "upnp_browser"... Sep 16 14:30:12 volumio volumio[3591]: info: Loading plugin "networkfs"... Sep 16 14:30:12 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 14:30:12 volumio volumio[3591]: info: Loading plugin "alarm-clock"... Sep 16 14:30:12 volumio sudo[3653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //SERVER5/music2 /mnt/NAS/music2 Sep 16 14:30:12 volumio sudo[3653]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:13 volumio volumio[3591]: info: Loading plugin "airplay_emulation"... Sep 16 14:30:13 volumio volumio[3591]: info: [1758033013200] Starting Shairport Sync Sep 16 14:30:13 volumio volumio[3591]: info: Loading plugin "last_100"... Sep 16 14:30:13 volumio volumio[3591]: info: Loading plugin "webradio"... Sep 16 14:30:13 volumio volumio[3591]: info: Loading plugin "i2s_dacs"... Sep 16 14:30:13 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 14:30:13 volumio volumio[3591]: info: Loading plugin "volumiodiscovery"... Sep 16 14:30:13 volumio volumio[3591]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:13 volumio node[3591]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:13 volumio node[3591]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:13 volumio node[3591]: *** WARNING *** For more information see Sep 16 14:30:13 volumio node[3591]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:13 volumio node[3591]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:13 volumio node[3591]: *** WARNING *** For more information see Sep 16 14:30:13 volumio volumio[3591]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:13 volumio volumio[3591]: *** WARNING *** For more information see Sep 16 14:30:13 volumio volumio[3591]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:13 volumio volumio[3591]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:13 volumio volumio[3591]: *** WARNING *** For more information see Sep 16 14:30:13 volumio volumio[3591]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 14:30:13 volumio volumio[3591]: Discovery: StartAdv! undefined Sep 16 14:30:13 volumio volumio[3591]: Discovery: Started advertising... Volumio - undefined Sep 16 14:30:13 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 14:30:13 volumio volumio[3591]: info: Loading plugin "albumart"... Sep 16 14:30:13 volumio volumio[3591]: info: Plugin example_plugin is not enabled Sep 16 14:30:13 volumio volumio[3591]: info: Loading plugin "updater_comm"... Sep 16 14:30:14 volumio volumio[3591]: info: Plugin mpdemulation is not enabled Sep 16 14:30:14 volumio volumio[3591]: info: Loading plugin "rest_api"... Sep 16 14:30:14 volumio volumio[3591]: info: Loading plugin "websocket"... Sep 16 14:30:14 volumio volumio[3591]: info: Loading plugin "snapcast"... Sep 16 14:30:14 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:487: Not connected Sep 16 14:30:14 volumio kernel: usb 1-1: USB disconnect, device number 16 Sep 16 14:30:14 volumio kernel: usb 1-1.1: USB disconnect, device number 17 Sep 16 14:30:14 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Sep 16 14:30:14 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:14 volumio avahi-daemon[617]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 16 14:30:14 volumio avahi-daemon[617]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.2.231. Sep 16 14:30:14 volumio dhcpcd[622]: eth0: arp if_readrawpacket: Network is down Sep 16 14:30:14 volumio dhcpcd[622]: eth0: carrier lost Sep 16 14:30:14 volumio netplugd[873]: eth0: state ACTIVE flags 0x00011043 UP,BROADCAST,RUNNING,MULTICAST,10000 -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:14 volumio netplugd[3688]: /etc/netplug/netplug eth0 probe -> pid 3688 Sep 16 14:30:14 volumio avahi-daemon[617]: Withdrawing address record for 192.168.2.231 on eth0. Sep 16 14:30:14 volumio avahi-daemon[617]: Withdrawing workstation service for eth0. Sep 16 14:30:14 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:14 volumio systemd[1]: Stopping ifup for eth0... Sep 16 14:30:14 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:14 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:14 volumio systemd[1]: Starting Wireless Services... Sep 16 14:30:14 volumio dhcpcd[622]: eth0: deleting route to 192.168.2.0/24 Sep 16 14:30:14 volumio dhcpcd[622]: eth0: deleting default route via 192.168.2.90 Sep 16 14:30:14 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:14 volumio sudo[3726]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Sep 16 14:30:14 volumio ifdown[3703]: dhcpcd[3734]: sending commands to master dhcpcd process Sep 16 14:30:14 volumio dhcpcd[3734]: sending commands to master dhcpcd process Sep 16 14:30:14 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:14 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:14 volumio sudo[3726]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:14 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:14 volumio sudo[3726]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:14 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Sep 16 14:30:14 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:15 volumio ifdown[3703]: Cannot find device "eth0" Sep 16 14:30:15 volumio dhcpcd[622]: eth0: removing interface Sep 16 14:30:15 volumio kernel: usb 1-1: new high-speed USB device number 18 using dwc_otg Sep 16 14:30:15 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Sep 16 14:30:15 volumio systemd[1]: Stopped ifup for eth0. Sep 16 14:30:15 volumio dhcpcd[622]: control command: dhcpcd -k eth0 Sep 16 14:30:15 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514 Sep 16 14:30:15 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:15 volumio kernel: hub 1-1:1.0: USB hub found Sep 16 14:30:15 volumio kernel: hub 1-1:1.0: 5 ports detected Sep 16 14:30:15 volumio kernel: usb 1-1.1: new high-speed USB device number 19 using dwc_otg Sep 16 14:30:15 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 Sep 16 14:30:15 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:15 volumio kernel: smsc95xx v1.0.5 Sep 16 14:30:15 volumio volumio[3591]: info: SnapCast initiated Sep 16 14:30:15 volumio volumio[3591]: info: ___________ START PLUGINS ___________ Sep 16 14:30:16 volumio volumio[3591]: info: PLUGIN START: appearance Sep 16 14:30:16 volumio volumio[3591]: info: PLUGIN START: wizard Sep 16 14:30:16 volumio volumio[3591]: info: PLUGIN START: upnp_browser Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 14:30:16 volumio volumio[3591]: info: [1758033016030] CoreMusicLibrary::Adding element Media Servers Sep 16 14:30:16 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:57:e1:ca Sep 16 14:30:16 volumio netplugd[873]: eth0: state ACTIVE flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:16 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:16 volumio netplugd[873]: eth0: state WAIT_IN flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:16 volumio netplugd[3815]: /etc/netplug/netplug eth0 probe -> pid 3815 Sep 16 14:30:16 volumio volumio[3591]: info: PLUGIN START: last_100 Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 14:30:16 volumio volumio[3591]: info: [1758033016146] CoreMusicLibrary::Adding element Last_100 Sep 16 14:30:16 volumio volumio[3591]: info: PLUGIN START: webradio Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 14:30:16 volumio volumio[3591]: info: [1758033016155] CoreMusicLibrary::Adding element Webradio Sep 16 14:30:16 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 14:30:16 volumio volumio[3591]: info: PLUGIN START: snapcast Sep 16 14:30:16 volumio volumio[3591]: info: Starting SnapCast services... Sep 16 14:30:16 volumio systemd[1]: Stopping Wireless Services... Sep 16 14:30:16 volumio dhcpcd[622]: eth0: adding address fe80::c281:4297:a465:28fe Sep 16 14:30:16 volumio dhcpcd[622]: if_addaddress6: Permission denied Sep 16 14:30:16 volumio systemd[1]: Starting Wireless Services... Sep 16 14:30:16 volumio systemd[1]: Starting ifup for eth0... Sep 16 14:30:16 volumio volumio[3591]: info: Loading i18n strings for locale en Sep 16 14:30:16 volumio systemd[1]: Started ifup for eth0. Sep 16 14:30:16 volumio dhcpcd[622]: eth0: waiting for carrier Sep 16 14:30:16 volumio sudo[3832]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Sep 16 14:30:16 volumio volumio[3591]: Updating browse sources language Sep 16 14:30:16 volumio volumio[3591]: Cannot find translation for sourceMedia Servers Sep 16 14:30:16 volumio sudo[3837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapserver Sep 16 14:30:16 volumio sudo[3832]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:16 volumio sudo[3832]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:16 volumio sudo[3837]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:16 volumio ifup[3840]: dhcpcd[3866]: sending commands to master dhcpcd process Sep 16 14:30:16 volumio dhcpcd[3866]: sending commands to master dhcpcd process Sep 16 14:30:16 volumio dhcpcd[622]: control command: dhcpcd eth0 Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::initPlayerControls Sep 16 14:30:16 volumio systemd[1]: Stopping Snapcast server... Sep 16 14:30:16 volumio snapserver[3095]: Received SIGTERM signal. Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 16 14:30:16 volumio snapserver[3095]: Exception in StreamSession::reader(): read_some: Bad file descriptor Sep 16 14:30:16 volumio snapserver[3095]: daemon terminated. Sep 16 14:30:16 volumio systemd[1]: Starting Snapcast server... Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:16 volumio snapserver[3897]: Settings file: "/var/lib/snapserver/server.json" Sep 16 14:30:16 volumio snapserver[3897]: pipe:///tmp/snapfifo?name=VOLUMIO-MPD&mode=read Sep 16 14:30:16 volumio snapserver[3897]: 2025-09-16 14-30-16 [Notice] Settings file: "/var/lib/snapserver/server.json" Sep 16 14:30:16 volumio snapserver[3901]: daemon started Sep 16 14:30:16 volumio systemd[1]: Started Snapcast server. Sep 16 14:30:16 volumio sudo[3837]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 14:30:16 volumio volumio[3591]: info: BOOT COMPLETED Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 14:30:16 volumio volumio[3591]: info: CoreCommandRouter::Close All Modals sent Sep 16 14:30:16 volumio volumio[3591]: Express server listening on port 3000 Sep 16 14:30:16 volumio volumio[3591]: Volumio Calling Home Sep 16 14:30:17 volumio netplugd[873]: eth0: state ACTIVE flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[3948]: /etc/netplug/netplug eth0 probe -> pid 3948 Sep 16 14:30:17 volumio netplugd[873]: eth0: state ACTIVE flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state ACTIVE flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state ACTIVE flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state DOWN flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[3949]: /etc/netplug/netplug eth0 out -> pid 3949 Sep 16 14:30:17 volumio netplugd[3950]: /etc/netplug/netplug eth0 out -> pid 3950 Sep 16 14:30:17 volumio netplugd[3951]: /etc/netplug/netplug eth0 out -> pid 3951 Sep 16 14:30:17 volumio netplugd[3952]: /etc/netplug/netplug eth0 out -> pid 3952 Sep 16 14:30:17 volumio netplugd[873]: eth0: state OUTING pid 3949 exited status 0 Sep 16 14:30:17 volumio netplugd[873]: eth0: state OUTING pid 3950 exited status 0 Sep 16 14:30:17 volumio netplugd[873]: eth0: state OUTING pid 3951 exited status 0 Sep 16 14:30:17 volumio systemd[1]: Reloading OpenBSD Secure Shell server. Sep 16 14:30:17 volumio systemd[1]: Stopping Wireless Services... Sep 16 14:30:17 volumio systemd[1]: Starting Wireless Services... Sep 16 14:30:17 volumio ntpd[800]: Deleting interface #7 eth0, 192.168.2.231#123, interface stats: received=4, sent=4, dropped=0, active_time=10 secs Sep 16 14:30:17 volumio ntpd[800]: 172.234.44.141 interface 192.168.2.231 -> (none) Sep 16 14:30:17 volumio ntpd[800]: 193.29.63.226 interface 192.168.2.231 -> (none) Sep 16 14:30:17 volumio ntpd[800]: 198.137.202.56 interface 192.168.2.231 -> (none) Sep 16 14:30:17 volumio ntpd[800]: 162.244.81.139 interface 192.168.2.231 -> (none) Sep 16 14:30:17 volumio ntpd[800]: peers refreshed Sep 16 14:30:17 volumio sudo[3961]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Sep 16 14:30:17 volumio sshd[803]: Received SIGHUP; restarting. Sep 16 14:30:17 volumio systemd[1]: Reloaded OpenBSD Secure Shell server. Sep 16 14:30:17 volumio sudo[3961]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:17 volumio sudo[3961]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:17 volumio sshd[803]: Server listening on 0.0.0.0 port 22. Sep 16 14:30:17 volumio sshd[803]: Server listening on :: port 22. Sep 16 14:30:17 volumio volumio[3591]: info: CoreStateMachine::resetVolumioState Sep 16 14:30:17 volumio volumio[3591]: info: CoreStateMachine::getcurrentVolume Sep 16 14:30:17 volumio volumio[3591]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 14:30:17 volumio volumio[3591]: info: CoreStateMachine::updateTrackBlock Sep 16 14:30:17 volumio volumio[3591]: info: CorePlayQueue::getTrackBlock Sep 16 14:30:17 volumio netplugd[873]: eth0: state INACTIVE flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00011043 UP,BROADCAST,RUNNING,MULTICAST,10000 Sep 16 14:30:17 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state INACTIVE flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:17 volumio netplugd[3990]: /etc/netplug/netplug eth0 in -> pid 3990 Sep 16 14:30:17 volumio netplugd[873]: eth0: state INACTIVE flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:17 volumio dhcpcd[622]: eth0: carrier acquired Sep 16 14:30:17 volumio netplugd[873]: eth0: state INACTIVE flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state OUTING flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:17 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001003 UP,BROADCAST,MULTICAST -> 0x00001043 UP,BROADCAST,RUNNING,MULTICAST Sep 16 14:30:17 volumio netplugd[3992]: /etc/netplug/netplug eth0 in -> pid 3992 Sep 16 14:30:17 volumio netplugd[3991]: /etc/netplug/netplug eth0 in -> pid 3991 Sep 16 14:30:17 volumio netplugd[873]: eth0: state INNING pid 3990 exited status 0 Sep 16 14:30:17 volumio dhcpcd[622]: eth0: IAID eb:57:e1:ca Sep 16 14:30:17 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Sep 16 14:30:17 volumio netplugd[3994]: /etc/netplug/netplug eth0 in -> pid 3994 Sep 16 14:30:17 volumio netplugd[873]: eth0: state INNING pid 3992 exited status 0 Sep 16 14:30:17 volumio netplugd[873]: eth0: state INNING pid 3991 exited status 0 Sep 16 14:30:17 volumio netplugd[873]: eth0: state INNING pid 3994 exited status 0 Sep 16 14:30:17 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:487: Not connected Sep 16 14:30:17 volumio kernel: usb 1-1: USB disconnect, device number 18 Sep 16 14:30:17 volumio kernel: usb 1-1.1: USB disconnect, device number 19 Sep 16 14:30:17 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Sep 16 14:30:17 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:17 volumio dhcpcd[622]: eth0: carrier lost Sep 16 14:30:17 volumio netplugd[873]: eth0: state ACTIVE flags 0x00011043 UP,BROADCAST,RUNNING,MULTICAST,10000 -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:17 volumio netplugd[3996]: /etc/netplug/netplug eth0 probe -> pid 3996 Sep 16 14:30:17 volumio avahi-daemon[617]: Withdrawing workstation service for eth0. Sep 16 14:30:17 volumio systemd[1]: Stopping ifup for eth0... Sep 16 14:30:17 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:17 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:17 volumio systemd[1]: Stopping Wireless Services... Sep 16 14:30:17 volumio systemd[1]: Starting Wireless Services... Sep 16 14:30:17 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:17 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:17 volumio dhcpcd[622]: eth0: removing interface Sep 16 14:30:17 volumio ifdown[4005]: dhcpcd[4037]: sending commands to master dhcpcd process Sep 16 14:30:17 volumio dhcpcd[4037]: sending commands to master dhcpcd process Sep 16 14:30:17 volumio sudo[4031]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Sep 16 14:30:17 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:17 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:17 volumio sudo[4031]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:17 volumio ifdown[4005]: Cannot find device "eth0" Sep 16 14:30:17 volumio sudo[4031]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:17 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:17 volumio dhcpcd[622]: control command: dhcpcd -k eth0 Sep 16 14:30:17 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Sep 16 14:30:18 volumio snapserver[3901]: ControlServer::NewConnection: ::ffff:127.0.0.1 Sep 16 14:30:18 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:18 volumio netplugd[873]: eth0: can't get flags: No such device Sep 16 14:30:18 volumio kernel: usb 1-1: new high-speed USB device number 20 using dwc_otg Sep 16 14:30:18 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Sep 16 14:30:18 volumio systemd[1]: Stopped ifup for eth0. Sep 16 14:30:18 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514 Sep 16 14:30:18 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:18 volumio kernel: hub 1-1:1.0: USB hub found Sep 16 14:30:18 volumio kernel: hub 1-1:1.0: 5 ports detected Sep 16 14:30:18 volumio sudo[3653]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:18 volumio volumio[3591]: info: Setting Device type: Raspberry PI Sep 16 14:30:18 volumio volumio[3591]: info: snapserver started Sep 16 14:30:18 volumio volumio[3591]: info: MPD running with PID724 ,establishing connection Sep 16 14:30:18 volumio kernel: usb 1-1.1: new high-speed USB device number 21 using dwc_otg Sep 16 14:30:18 volumio sudo[4107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapclient Sep 16 14:30:18 volumio sudo[4107]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:18 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 Sep 16 14:30:18 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:18 volumio kernel: smsc95xx v1.0.5 Sep 16 14:30:18 volumio systemd[1]: Stopping Snapcast client... Sep 16 14:30:18 volumio snapclient[3369]: Received SIGTERM signal. Sep 16 14:30:18 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:57:e1:ca Sep 16 14:30:18 volumio netplugd[873]: : state DOWN flags 0x0198d4f8 LOOPBACK,POINTOPOINT,NOTRAILERS,RUNNING,NOARP,MASTER,MULTICAST,198c000 -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:18 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:18 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:18 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:18 volumio netplugd[873]: eth0: state ACTIVE flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:18 volumio netplugd[873]: eth0: state ACTIVE flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:18 volumio netplugd[873]: eth0: state ACTIVE flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:18 volumio netplugd[4118]: /etc/netplug/netplug eth0 probe -> pid 4118 Sep 16 14:30:18 volumio netplugd[873]: eth0: state OUTING flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:18 volumio netplugd[873]: eth0: state PROBING_UP flags 0x00001043 UP,BROADCAST,RUNNING,MULTICAST -> 0x00001002 BROADCAST,MULTICAST Sep 16 14:30:18 volumio netplugd[4119]: /etc/netplug/netplug eth0 probe -> pid 4119 Sep 16 14:30:18 volumio netplugd[4120]: /etc/netplug/netplug eth0 probe -> pid 4120 Sep 16 14:30:19 volumio snapclient[3369]: daemon terminated. Sep 16 14:30:19 volumio systemd[1]: Starting Snapcast client... Sep 16 14:30:19 volumio systemd[1]: Starting ifup for eth0... Sep 16 14:30:19 volumio systemd[1]: Started ifup for eth0. Sep 16 14:30:19 volumio snapclient[4140]: daemon started Sep 16 14:30:19 volumio systemd[1]: Started Snapcast client. Sep 16 14:30:19 volumio sudo[4107]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:19 volumio volumio[3591]: info: VolumeController:: Volume=10 Mute =false Sep 16 14:30:19 volumio volumio[3591]: info: CoreStateMachine::pushState Sep 16 14:30:19 volumio volumio[3591]: info: CoreStateMachine::getState Sep 16 14:30:19 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:19 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:19 volumio volumio[3591]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:19 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:19 volumio netplugd[873]: eth0: state DOWN flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:19 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:19 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:19 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:19 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:19 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:19 volumio netplugd[873]: eth0: state PROBING flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:19 volumio netplugd[873]: eth0: state DOWNANDOUT flags 0x00001002 BROADCAST,MULTICAST -> 0x00001003 UP,BROADCAST,MULTICAST Sep 16 14:30:19 volumio netplugd[873]: eth0: unexpected state DOWNANDOUT for UP Sep 16 14:30:19 volumio dhcpcd[622]: eth0: adding address fe80::c281:4297:a465:28fe Sep 16 14:30:19 volumio dhcpcd[622]: if_addaddress6: Permission denied Sep 16 14:30:19 volumio volumio[3591]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:19 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:19 volumio volumio[3591]: info: interfaceApi::pushState Sep 16 14:30:19 volumio volumio[3591]: info: Cannot mount NAS music2 at system boot, trial number 1 ,retrying in 5 seconds Sep 16 14:30:19 volumio systemd[1]: Stopping Wireless Services... Sep 16 14:30:19 volumio systemd[1]: Starting Wireless Services... Sep 16 14:30:19 volumio dhcpcd[4165]: sending commands to master dhcpcd process Sep 16 14:30:19 volumio ifup[4135]: dhcpcd[4165]: sending commands to master dhcpcd process Sep 16 14:30:19 volumio systemd[1]: Stopping Wireless Services... Sep 16 14:30:19 volumio dhcpcd[622]: eth0: waiting for carrier Sep 16 14:30:19 volumio dhcpcd[622]: control command: dhcpcd eth0 Sep 16 14:30:19 volumio systemd[1]: Starting Wireless Services... Sep 16 14:30:19 volumio systemd[1]: wireless.service start request repeated too quickly, refusing to start. Sep 16 14:30:19 volumio systemd[1]: Failed to start Wireless Services. Sep 16 14:30:19 volumio systemd[1]: Unit wireless.service entered failed state. Sep 16 14:30:19 volumio volumio[3591]: info: Sep 16 14:30:19 volumio volumio[3591]: ---------------------------- Client requests Volume 10 Sep 16 14:30:19 volumio volumio[3591]: info: VolumeController::SetAlsaVolume10 Sep 16 14:30:19 volumio volumio[3591]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} Sep 16 14:30:19 volumio systemd[1]: Reloading OpenBSD Secure Shell server. Sep 16 14:30:19 volumio volumio[3591]: info: snapclient started Sep 16 14:30:19 volumio sshd[803]: Received SIGHUP; restarting. Sep 16 14:30:19 volumio systemd[1]: Reloaded OpenBSD Secure Shell server. Sep 16 14:30:19 volumio sshd[803]: Server listening on 0.0.0.0 port 22. Sep 16 14:30:19 volumio sshd[803]: Server listening on :: port 22. Sep 16 14:30:20 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:20 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:20 volumio volumio[3591]: info: Reloading queue from file Sep 16 14:30:20 volumio volumio[3591]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:30:20 volumio snapserver[3901]: Exception in ControlSession::reader(): read_until: End of file Sep 16 14:30:20 volumio volumio[3591]: info: Sep 16 14:30:20 volumio dhcpcd[622]: eth0: carrier acquired Sep 16 14:30:20 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Sep 16 14:30:20 volumio dhcpcd[622]: eth0: IAID eb:57:e1:ca Sep 16 14:30:20 volumio dhcpcd[622]: eth0: rebinding lease of 192.168.2.231 Sep 16 14:30:20 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:487: Not connected Sep 16 14:30:20 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Sep 16 14:30:20 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Sep 16 14:30:20 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Sep 16 14:30:20 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Failed to read reg index 0x00000114: -71 Sep 16 14:30:20 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Error reading MII_ACCESS Sep 16 14:30:20 volumio kernel: smsc95xx 1-1.1:1.0 eth0: MII is busy in smsc95xx_mdio_read Sep 16 14:30:20 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Failed to read MII_BMSR Sep 16 14:30:21 volumio dhcpcd[622]: eth0: carrier lost Sep 16 14:30:21 volumio avahi-daemon[617]: Withdrawing workstation service for eth0. Sep 16 14:30:21 volumio kernel: usb 1-1: USB disconnect, device number 20 Sep 16 14:30:21 volumio kernel: usb 1-1.1: USB disconnect, device number 21 Sep 16 14:30:21 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Sep 16 14:30:21 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:21 volumio volumio-remote-updater[604]: [2025-09-16 14:30:21] [connect] Successful connection Sep 16 14:30:21 volumio systemd[1]: Stopping ifup for eth0... Sep 16 14:30:21 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:21 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:21 volumio ifdown[4256]: dhcpcd[4271]: sending commands to master dhcpcd process Sep 16 14:30:21 volumio dhcpcd[4271]: sending commands to master dhcpcd process Sep 16 14:30:21 volumio ifdown[4256]: Cannot find device "eth0" Sep 16 14:30:21 volumio systemd[1]: Stopped ifup for eth0. Sep 16 14:30:21 volumio dhcpcd[622]: eth0: removing interface Sep 16 14:30:21 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Sep 16 14:30:21 volumio dhcpcd[622]: control command: dhcpcd -k eth0 Sep 16 14:30:21 volumio kernel: usb 1-1: new high-speed USB device number 22 using dwc_otg Sep 16 14:30:21 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Sep 16 14:30:21 volumio volumio[3591]: null Sep 16 14:30:21 volumio volumio[3591]: info: VolumeController::Volume 10 Sep 16 14:30:21 volumio volumio[3591]: info: CoreStateMachine::pushState Sep 16 14:30:21 volumio volumio[3591]: info: CoreStateMachine::getState Sep 16 14:30:21 volumio volumio[3591]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:21 volumio volumio[3591]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:21 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:21 volumio volumio[3591]: info: interfaceApi::pushState Sep 16 14:30:21 volumio volumio[3591]: info: Setting volume on startup at 10 Sep 16 14:30:21 volumio volumio[3591]: info: CoreStateMachine::setRepeat true single undefined Sep 16 14:30:21 volumio volumio[3591]: info: CoreStateMachine::pushState Sep 16 14:30:21 volumio volumio[3591]: info: CoreStateMachine::getState Sep 16 14:30:21 volumio volumio[3591]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:21 volumio volumio[3591]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:21 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:21 volumio volumio[3591]: info: interfaceApi::pushState Sep 16 14:30:21 volumio volumio[3591]: info: CoreStateMachine::setRandom true Sep 16 14:30:21 volumio volumio[3591]: info: CoreStateMachine::pushState Sep 16 14:30:21 volumio volumio[3591]: info: CoreStateMachine::getState Sep 16 14:30:21 volumio volumio[3591]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:21 volumio volumio[3591]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:21 volumio volumio[3591]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:21 volumio volumio[3591]: info: interfaceApi::pushState Sep 16 14:30:21 volumio volumio[3591]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:30:21 volumio volumio[3591]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:30:21 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514 Sep 16 14:30:21 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:21 volumio kernel: hub 1-1:1.0: USB hub found Sep 16 14:30:21 volumio kernel: hub 1-1:1.0: 5 ports detected Sep 16 14:30:21 volumio volumio-remote-updater[604]: [2025-09-16 14:30:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1758033021 101 Sep 16 14:30:21 volumio volumio[3591]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:30:21 volumio volumio[3591]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:30:22 volumio volumio[3591]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:30:22 volumio volumio[3591]: info: mDNS: A device disapperared from network Sep 16 14:30:22 volumio volumio[3591]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 16 14:30:22 volumio sudo[4334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Sep 16 14:30:22 volumio sudo[4334]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:22 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Sep 16 14:30:22 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Sep 16 14:30:22 volumio systemd[1]: Started ShairportSync AirTunes receiver. Sep 16 14:30:22 volumio volumio[3591]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:30:22 volumio volumio[3591]: info: mDNS: A device disapperared from network Sep 16 14:30:22 volumio volumio[3591]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 16 14:30:22 volumio kernel: usb 1-1.1: new high-speed USB device number 23 using dwc_otg Sep 16 14:30:22 volumio sudo[4334]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:22 volumio shairport-sync[4347]: Successful Startup Sep 16 14:30:22 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:22 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:22 volumio volumio[3591]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 14:30:22 volumio volumio[3591]: { Error: addMembership ENODEV Sep 16 14:30:22 volumio volumio[3591]: at exports._errnoException (util.js:1018:11) Sep 16 14:30:22 volumio volumio[3591]: at Socket.addMembership (dgram.js:506:11) Sep 16 14:30:22 volumio volumio[3591]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:251:16) Sep 16 14:30:22 volumio volumio[3591]: at ontimeout (timers.js:386:14) Sep 16 14:30:22 volumio volumio[3591]: at tryOnTimeout (timers.js:250:5) Sep 16 14:30:22 volumio volumio[3591]: at Timer.listOnTimeout (timers.js:214:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Sep 16 14:30:22 volumio volumio[3591]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 14:30:22 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 Sep 16 14:30:22 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:22 volumio kernel: smsc95xx v1.0.5 Sep 16 14:30:22 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:57:e1:ca Sep 16 14:30:22 volumio dhcpcd[622]: eth0: adding address fe80::c281:4297:a465:28fe Sep 16 14:30:22 volumio dhcpcd[622]: if_addaddress6: Permission denied Sep 16 14:30:22 volumio systemd[1]: Starting ifup for eth0... Sep 16 14:30:22 volumio systemd[1]: Started ifup for eth0. Sep 16 14:30:22 volumio ifup[4373]: dhcpcd[4392]: sending commands to master dhcpcd process Sep 16 14:30:22 volumio dhcpcd[4392]: sending commands to master dhcpcd process Sep 16 14:30:22 volumio dhcpcd[622]: eth0: waiting for carrier Sep 16 14:30:22 volumio dhcpcd[622]: control command: dhcpcd eth0 Sep 16 14:30:22 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:22 volumio systemd[1]: Reloading OpenBSD Secure Shell server. Sep 16 14:30:22 volumio sshd[803]: Received SIGHUP; restarting. Sep 16 14:30:23 volumio systemd[1]: Reloaded OpenBSD Secure Shell server. Sep 16 14:30:23 volumio sshd[803]: Server listening on 0.0.0.0 port 22. Sep 16 14:30:23 volumio sshd[803]: Server listening on :: port 22. Sep 16 14:30:23 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:23 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:23 volumio sudo[4463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 14:29 Sep 16 14:30:23 volumio sudo[4463]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:24 volumio dhcpcd[622]: eth0: carrier acquired Sep 16 14:30:24 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Sep 16 14:30:24 volumio dhcpcd[622]: eth0: IAID eb:57:e1:ca Sep 16 14:30:24 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:24 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:24 volumio sudo[4463]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:24 volumio dhcpcd[622]: eth0: soliciting an IPv6 router Sep 16 14:30:24 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Sep 16 14:30:24 volumio volumio-remote-updater[604]: [2025-09-16 14:30:24] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 16 14:30:25 volumio volumio-remote-updater[604]: [2025-09-16 14:30:24] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 16 14:30:25 volumio systemd[1]: Unit volumio.service entered failed state. Sep 16 14:30:25 volumio systemd[1]: Starting dynamicswap service... Sep 16 14:30:25 volumio systemd[1]: Started dynamicswap service. Sep 16 14:30:25 volumio dhcpcd[622]: eth0: rebinding lease of 192.168.2.231 Sep 16 14:30:25 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:25 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:25 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Sep 16 14:30:25 volumio systemd[1]: Starting dynamicswap service... Sep 16 14:30:25 volumio systemd[1]: Started dynamicswap service. Sep 16 14:30:25 volumio systemd[1]: Stopping Volumio Backend Module... Sep 16 14:30:25 volumio systemd[1]: Starting Volumio Backend Module... Sep 16 14:30:25 volumio systemd[1]: Started Volumio Backend Module. Sep 16 14:30:26 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:26 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:27 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:27 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:28 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:28 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:29 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:29 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:29 volumio volumio[4488]: info: ------------------------------------------- Sep 16 14:30:29 volumio volumio[4488]: info: ----- Volumio2 ---- Sep 16 14:30:29 volumio volumio[4488]: info: ------------------------------------------- Sep 16 14:30:29 volumio volumio[4488]: info: ----- System startup ---- Sep 16 14:30:29 volumio volumio[4488]: info: ------------------------------------------- Sep 16 14:30:29 volumio volumio-remote-updater[604]: [2025-09-16 14:30:29] [connect] Successful connection Sep 16 14:30:30 volumio volumio[4488]: info: Plugin folders cleanup Sep 16 14:30:30 volumio volumio[4488]: info: Scanning into folder /volumio/app/plugins/ Sep 16 14:30:30 volumio volumio[4488]: info: Scanning category audio_interface Sep 16 14:30:30 volumio volumio[4488]: info: Scanning category miscellanea Sep 16 14:30:30 volumio volumio[4488]: info: Scanning category music_service Sep 16 14:30:30 volumio volumio[4488]: info: Scanning category plugins.json Sep 16 14:30:30 volumio volumio[4488]: info: Scanning category system_controller Sep 16 14:30:30 volumio volumio[4488]: info: Scanning category user_interface Sep 16 14:30:30 volumio volumio[4488]: info: Scanning into folder /data/plugins/ Sep 16 14:30:30 volumio volumio[4488]: info: Scanning category miscellanea Sep 16 14:30:30 volumio volumio[4488]: info: Plugin folders cleanup completed Sep 16 14:30:30 volumio volumio[4488]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 14:30:30 volumio volumio[4488]: info: Loading plugins from folder /data/plugins/ Sep 16 14:30:30 volumio volumio[4488]: info: Loading plugin "system"... Sep 16 14:30:30 volumio volumio[4488]: info: Loading plugin "appearance"... Sep 16 14:30:30 volumio snapclient[4140]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:30 volumio snapclient[4140]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:30 volumio dhcpcd[622]: eth0: leased 192.168.2.231 for 7200 seconds Sep 16 14:30:30 volumio dhcpcd[622]: eth0: adding route to 192.168.2.0/24 Sep 16 14:30:30 volumio dhcpcd[622]: eth0: adding default route via 192.168.2.90 Sep 16 14:30:30 volumio avahi-daemon[617]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.231. Sep 16 14:30:30 volumio avahi-daemon[617]: New relevant interface eth0.IPv4 for mDNS. Sep 16 14:30:30 volumio avahi-daemon[617]: Registering new address record for 192.168.2.231 on eth0.IPv4. Sep 16 14:30:31 volumio snapclient[4140]: Connected to 192.168.2.231 Sep 16 14:30:31 volumio snapserver[3901]: StreamServer::NewConnection: ::ffff:192.168.2.231 Sep 16 14:30:31 volumio snapserver[3901]: Session inactive. Removing Sep 16 14:30:32 volumio ntpd[800]: Listen normally on 8 eth0 192.168.2.231 UDP 123 Sep 16 14:30:32 volumio ntpd[800]: peers refreshed Sep 16 14:30:32 volumio volumio[4488]: info: Loading plugin "network"... Sep 16 14:30:32 volumio volumio[4488]: info: Loading plugin "services"... Sep 16 14:30:32 volumio volumio[4488]: info: Loading plugin "alsa_controller"... Sep 16 14:30:32 volumio volumio[4488]: info: Loading plugin "wizard"... Sep 16 14:30:32 volumio volumio[4488]: info: Loading plugin "volumio_command_line_client"... Sep 16 14:30:32 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:32 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:32 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:32 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:32 volumio volumio[4488]: info: Loading plugin "upnp"... Sep 16 14:30:32 volumio volumio[4488]: info: [1758033032756] Starting Upmpd Daemon Sep 16 14:30:32 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 14:30:32 volumio volumio[4488]: info: Loading plugin "mpd"... Sep 16 14:30:33 volumio volumio[4488]: info: Loading plugin "upnp_browser"... Sep 16 14:30:34 volumio volumio[4488]: info: Loading plugin "networkfs"... Sep 16 14:30:34 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 14:30:34 volumio volumio[4488]: info: Loading plugin "alarm-clock"... Sep 16 14:30:34 volumio sudo[4550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //SERVER5/music2 /mnt/NAS/music2 Sep 16 14:30:34 volumio sudo[4550]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:34 volumio volumio[4488]: info: Loading plugin "airplay_emulation"... Sep 16 14:30:34 volumio volumio[4488]: info: [1758033034796] Starting Shairport Sync Sep 16 14:30:34 volumio volumio[4488]: info: Loading plugin "last_100"... Sep 16 14:30:34 volumio volumio[4488]: info: Loading plugin "webradio"... Sep 16 14:30:34 volumio volumio[4488]: info: Loading plugin "i2s_dacs"... Sep 16 14:30:34 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 14:30:34 volumio volumio[4488]: info: Loading plugin "volumiodiscovery"... Sep 16 14:30:34 volumio volumio[4488]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:34 volumio node[4488]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:34 volumio node[4488]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:34 volumio node[4488]: *** WARNING *** For more information see Sep 16 14:30:34 volumio node[4488]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:34 volumio node[4488]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:34 volumio node[4488]: *** WARNING *** For more information see Sep 16 14:30:34 volumio volumio[4488]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:34 volumio volumio[4488]: *** WARNING *** For more information see Sep 16 14:30:34 volumio volumio[4488]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:34 volumio volumio[4488]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:34 volumio volumio[4488]: *** WARNING *** For more information see Sep 16 14:30:34 volumio volumio[4488]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 14:30:34 volumio volumio[4488]: Discovery: StartAdv! undefined Sep 16 14:30:35 volumio volumio[4488]: Discovery: Started advertising... Volumio - undefined Sep 16 14:30:35 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 14:30:35 volumio volumio[4488]: info: Loading plugin "albumart"... Sep 16 14:30:35 volumio volumio[4488]: info: Plugin example_plugin is not enabled Sep 16 14:30:35 volumio volumio[4488]: info: Loading plugin "updater_comm"... Sep 16 14:30:35 volumio volumio[4488]: info: Plugin mpdemulation is not enabled Sep 16 14:30:35 volumio volumio[4488]: info: Loading plugin "rest_api"... Sep 16 14:30:35 volumio volumio[4488]: info: Loading plugin "websocket"... Sep 16 14:30:35 volumio volumio[4488]: info: Loading plugin "snapcast"... Sep 16 14:30:36 volumio kernel: ERROR::dwc_otg_hcd_urb_enqueue:487: Not connected Sep 16 14:30:36 volumio avahi-daemon[617]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 16 14:30:36 volumio avahi-daemon[617]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.2.231. Sep 16 14:30:36 volumio dhcpcd[622]: eth0: carrier lost Sep 16 14:30:36 volumio avahi-daemon[617]: Withdrawing address record for 192.168.2.231 on eth0. Sep 16 14:30:36 volumio avahi-daemon[617]: Withdrawing workstation service for eth0. Sep 16 14:30:36 volumio kernel: usb 1-1: USB disconnect, device number 22 Sep 16 14:30:36 volumio kernel: usb 1-1.1: USB disconnect, device number 23 Sep 16 14:30:36 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Sep 16 14:30:36 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:36 volumio systemd[1]: Stopping ifup for eth0... Sep 16 14:30:36 volumio ifdown[4601]: dhcpcd[4622]: sending commands to master dhcpcd process Sep 16 14:30:36 volumio dhcpcd[4622]: sending commands to master dhcpcd process Sep 16 14:30:36 volumio dhcpcd[622]: eth0: deleting route to 192.168.2.0/24 Sep 16 14:30:36 volumio dhcpcd[622]: eth0: deleting default route via 192.168.2.90 Sep 16 14:30:36 volumio ifdown[4601]: Cannot find device "eth0" Sep 16 14:30:36 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Sep 16 14:30:36 volumio dhcpcd[622]: eth0: removing interface Sep 16 14:30:36 volumio systemd[1]: Stopped ifup for eth0. Sep 16 14:30:36 volumio kernel: usb 1-1: new high-speed USB device number 24 using dwc_otg Sep 16 14:30:36 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Sep 16 14:30:37 volumio volumio[4488]: info: SnapCast initiated Sep 16 14:30:37 volumio volumio[4488]: info: ___________ START PLUGINS ___________ Sep 16 14:30:37 volumio volumio[4488]: info: PLUGIN START: appearance Sep 16 14:30:37 volumio volumio[4488]: info: PLUGIN START: wizard Sep 16 14:30:37 volumio volumio[4488]: info: PLUGIN START: upnp_browser Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 14:30:37 volumio volumio[4488]: info: [1758033037120] CoreMusicLibrary::Adding element Media Servers Sep 16 14:30:37 volumio dhcpcd[622]: control command: dhcpcd -k eth0 Sep 16 14:30:37 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514 Sep 16 14:30:37 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:37 volumio kernel: hub 1-1:1.0: USB hub found Sep 16 14:30:37 volumio kernel: hub 1-1:1.0: 5 ports detected Sep 16 14:30:37 volumio volumio[4488]: info: PLUGIN START: last_100 Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 14:30:37 volumio volumio[4488]: info: [1758033037178] CoreMusicLibrary::Adding element Last_100 Sep 16 14:30:37 volumio volumio[4488]: info: PLUGIN START: webradio Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 14:30:37 volumio volumio[4488]: info: [1758033037190] CoreMusicLibrary::Adding element Webradio Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 14:30:37 volumio volumio[4488]: info: PLUGIN START: snapcast Sep 16 14:30:37 volumio volumio[4488]: info: Starting SnapCast services... Sep 16 14:30:37 volumio volumio[4488]: info: Loading i18n strings for locale en Sep 16 14:30:37 volumio volumio[4488]: Updating browse sources language Sep 16 14:30:37 volumio volumio[4488]: Cannot find translation for sourceMedia Servers Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 14:30:37 volumio sudo[4683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapserver Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::initPlayerControls Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:37 volumio sudo[4683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 16 14:30:37 volumio systemd[1]: Stopping Snapcast server... Sep 16 14:30:37 volumio snapserver[3901]: Received SIGTERM signal. Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:37 volumio snapserver[3901]: Exception in StreamSession::reader(): read_some: End of file Sep 16 14:30:37 volumio snapserver[3901]: daemon terminated. Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 14:30:37 volumio systemd[1]: Starting Snapcast server... Sep 16 14:30:37 volumio volumio[4488]: info: BOOT COMPLETED Sep 16 14:30:37 volumio kernel: usb 1-1.1: new high-speed USB device number 25 using dwc_otg Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 14:30:37 volumio snapserver[4691]: Settings file: "/var/lib/snapserver/server.json" Sep 16 14:30:37 volumio snapserver[4691]: pipe:///tmp/snapfifo?name=VOLUMIO-MPD&mode=read Sep 16 14:30:37 volumio snapserver[4691]: 2025-09-16 14-30-37 [Notice] Settings file: "/var/lib/snapserver/server.json" Sep 16 14:30:37 volumio snapserver[4693]: daemon started Sep 16 14:30:37 volumio systemd[1]: Started Snapcast server. Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::Close All Modals sent Sep 16 14:30:37 volumio sudo[4683]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:37 volumio volumio[4488]: Express server listening on port 3000 Sep 16 14:30:37 volumio volumio[4488]: Volumio Calling Home Sep 16 14:30:37 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 Sep 16 14:30:37 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:37 volumio kernel: smsc95xx v1.0.5 Sep 16 14:30:37 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:57:e1:ca Sep 16 14:30:37 volumio dhcpcd[622]: eth0: adding address fe80::c281:4297:a465:28fe Sep 16 14:30:37 volumio dhcpcd[622]: if_addaddress6: Permission denied Sep 16 14:30:37 volumio systemd[1]: Starting ifup for eth0... Sep 16 14:30:37 volumio systemd[1]: Started ifup for eth0. Sep 16 14:30:37 volumio ifup[4717]: dhcpcd[4739]: sending commands to master dhcpcd process Sep 16 14:30:37 volumio dhcpcd[4739]: sending commands to master dhcpcd process Sep 16 14:30:37 volumio volumio[4488]: info: CoreStateMachine::resetVolumioState Sep 16 14:30:37 volumio volumio[4488]: info: CoreStateMachine::getcurrentVolume Sep 16 14:30:37 volumio volumio[4488]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 14:30:37 volumio dhcpcd[622]: eth0: waiting for carrier Sep 16 14:30:37 volumio dhcpcd[622]: control command: dhcpcd eth0 Sep 16 14:30:37 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:38 volumio volumio[4488]: info: CoreStateMachine::updateTrackBlock Sep 16 14:30:38 volumio volumio[4488]: info: CorePlayQueue::getTrackBlock Sep 16 14:30:38 volumio snapserver[4693]: ControlServer::NewConnection: ::ffff:127.0.0.1 Sep 16 14:30:38 volumio systemd[1]: Reloading OpenBSD Secure Shell server. Sep 16 14:30:38 volumio volumio[4488]: info: Setting Device type: Raspberry PI Sep 16 14:30:38 volumio volumio[4488]: info: snapserver started Sep 16 14:30:38 volumio sshd[803]: Received SIGHUP; restarting. Sep 16 14:30:38 volumio systemd[1]: Reloaded OpenBSD Secure Shell server. Sep 16 14:30:38 volumio sudo[4812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapclient Sep 16 14:30:38 volumio sshd[803]: Server listening on 0.0.0.0 port 22. Sep 16 14:30:38 volumio sshd[803]: Server listening on :: port 22. Sep 16 14:30:38 volumio volumio[4488]: info: MPD running with PID724 ,establishing connection Sep 16 14:30:38 volumio sudo[4812]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:38 volumio systemd[1]: Stopping Snapcast client... Sep 16 14:30:38 volumio snapclient[4140]: Received SIGTERM signal. Sep 16 14:30:38 volumio snapclient[4140]: daemon terminated. Sep 16 14:30:38 volumio systemd[1]: Starting Snapcast client... Sep 16 14:30:39 volumio snapclient[4830]: daemon started Sep 16 14:30:39 volumio systemd[1]: Started Snapcast client. Sep 16 14:30:39 volumio sudo[4812]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:39 volumio snapclient[4830]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:39 volumio snapclient[4830]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:39 volumio volumio[4488]: info: VolumeController:: Volume=10 Mute =false Sep 16 14:30:39 volumio volumio[4488]: info: CoreStateMachine::pushState Sep 16 14:30:39 volumio volumio[4488]: info: CoreStateMachine::getState Sep 16 14:30:39 volumio ntpd[800]: Deleting interface #8 eth0, 192.168.2.231#123, interface stats: received=4, sent=4, dropped=0, active_time=7 secs Sep 16 14:30:39 volumio ntpd[800]: 172.234.44.141 interface 192.168.2.231 -> (none) Sep 16 14:30:39 volumio ntpd[800]: 193.29.63.226 interface 192.168.2.231 -> (none) Sep 16 14:30:39 volumio ntpd[800]: 198.137.202.56 interface 192.168.2.231 -> (none) Sep 16 14:30:39 volumio ntpd[800]: 162.244.81.139 interface 192.168.2.231 -> (none) Sep 16 14:30:39 volumio ntpd[800]: peers refreshed Sep 16 14:30:39 volumio volumio[4488]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:39 volumio volumio[4488]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:39 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:39 volumio volumio[4488]: info: interfaceApi::pushState Sep 16 14:30:39 volumio volumio[4488]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} Sep 16 14:30:39 volumio dhcpcd[622]: eth0: carrier acquired Sep 16 14:30:39 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Sep 16 14:30:39 volumio dhcpcd[622]: eth0: IAID eb:57:e1:ca Sep 16 14:30:39 volumio volumio[4488]: info: snapclient started Sep 16 14:30:39 volumio sudo[4550]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:40 volumio snapclient[4830]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:40 volumio snapclient[4830]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:40 volumio dhcpcd[622]: eth0: rebinding lease of 192.168.2.231 Sep 16 14:30:40 volumio volumio[4488]: info: Reloading queue from file Sep 16 14:30:40 volumio volumio[4488]: info: Sep 16 14:30:40 volumio volumio[4488]: ---------------------------- Client requests Volume 10 Sep 16 14:30:40 volumio volumio[4488]: info: VolumeController::SetAlsaVolume10 Sep 16 14:30:40 volumio dhcpcd[622]: eth0: soliciting an IPv6 router Sep 16 14:30:40 volumio volumio[4488]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:30:40 volumio snapserver[4693]: Exception in ControlSession::reader(): read_until: End of file Sep 16 14:30:40 volumio volumio[4488]: info: Sep 16 14:30:40 volumio volumio[4488]: info: CoreStateMachine::setRepeat true single undefined Sep 16 14:30:40 volumio volumio[4488]: info: CoreStateMachine::pushState Sep 16 14:30:40 volumio volumio[4488]: info: CoreStateMachine::getState Sep 16 14:30:40 volumio volumio[4488]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:40 volumio volumio[4488]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:40 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:40 volumio volumio[4488]: info: interfaceApi::pushState Sep 16 14:30:40 volumio volumio[4488]: info: CoreStateMachine::setRandom true Sep 16 14:30:40 volumio volumio[4488]: info: CoreStateMachine::pushState Sep 16 14:30:40 volumio volumio[4488]: info: CoreStateMachine::getState Sep 16 14:30:40 volumio volumio[4488]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:40 volumio volumio[4488]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:40 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:40 volumio volumio[4488]: info: interfaceApi::pushState Sep 16 14:30:40 volumio volumio[4488]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:30:40 volumio volumio[4488]: null Sep 16 14:30:40 volumio volumio[4488]: info: VolumeController::Volume 10 Sep 16 14:30:40 volumio volumio[4488]: info: CoreStateMachine::pushState Sep 16 14:30:40 volumio volumio[4488]: info: CoreStateMachine::getState Sep 16 14:30:40 volumio volumio[4488]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:40 volumio volumio[4488]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:40 volumio volumio[4488]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:40 volumio volumio[4488]: info: interfaceApi::pushState Sep 16 14:30:40 volumio volumio[4488]: info: Cannot mount NAS music2 at system boot, trial number 1 ,retrying in 5 seconds Sep 16 14:30:40 volumio volumio[4488]: info: Setting volume on startup at 10 Sep 16 14:30:40 volumio volumio[4488]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:30:40 volumio volumio[4488]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:30:40 volumio volumio[4488]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:30:41 volumio snapclient[4830]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:41 volumio snapclient[4830]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:41 volumio sudo[4843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Sep 16 14:30:41 volumio sudo[4843]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:41 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Sep 16 14:30:41 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Sep 16 14:30:41 volumio systemd[1]: Started ShairportSync AirTunes receiver. Sep 16 14:30:41 volumio volumio[4488]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:30:41 volumio volumio[4488]: info: mDNS: A device disapperared from network Sep 16 14:30:41 volumio sudo[4843]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:41 volumio volumio[4488]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 16 14:30:41 volumio shairport-sync[4849]: Successful Startup Sep 16 14:30:41 volumio volumio[4488]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:30:41 volumio volumio[4488]: info: mDNS: A device disapperared from network Sep 16 14:30:41 volumio volumio[4488]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 16 14:30:42 volumio snapclient[4830]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:42 volumio snapclient[4830]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:42 volumio volumio[4488]: info: [1758033042125] Shairport-Sync Started Sep 16 14:30:42 volumio volumio[4488]: Error adding Membership: Error: addMembership EINVAL Sep 16 14:30:42 volumio snapserver[4693]: ControlServer::NewConnection: ::ffff:127.0.0.1 Sep 16 14:30:42 volumio snapserver[4693]: Session inactive. Removing Sep 16 14:30:42 volumio snapserver[4693]: Exception in ControlSession::reader(): read_until: End of file Sep 16 14:30:42 volumio volumio[4488]: info: CoreCommandRouter::volumioGetState Sep 16 14:30:42 volumio volumio[4488]: info: CoreStateMachine::getState Sep 16 14:30:42 volumio volumio[4488]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:42 volumio volumio-remote-updater[604]: [2025-09-16 14:30:42] [connect] Successful connection Sep 16 14:30:42 volumio volumio-remote-updater[604]: [2025-09-16 14:30:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1758033042 101 Sep 16 14:30:42 volumio volumio[4488]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 14:30:42 volumio volumio[4488]: { Error: addMembership ENODEV Sep 16 14:30:42 volumio volumio[4488]: at exports._errnoException (util.js:1018:11) Sep 16 14:30:42 volumio volumio[4488]: at Socket.addMembership (dgram.js:506:11) Sep 16 14:30:42 volumio volumio[4488]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:251:16) Sep 16 14:30:42 volumio volumio[4488]: at ontimeout (timers.js:386:14) Sep 16 14:30:42 volumio volumio[4488]: at tryOnTimeout (timers.js:250:5) Sep 16 14:30:42 volumio volumio[4488]: at Timer.listOnTimeout (timers.js:214:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Sep 16 14:30:42 volumio volumio[4488]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 14:30:43 volumio snapclient[4830]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:43 volumio snapclient[4830]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:44 volumio snapclient[4830]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:44 volumio snapclient[4830]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:44 volumio sudo[4866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 14:29 Sep 16 14:30:44 volumio sudo[4866]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:44 volumio sudo[4866]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:45 volumio snapclient[4830]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:45 volumio snapclient[4830]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:45 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Sep 16 14:30:45 volumio volumio-remote-updater[604]: [2025-09-16 14:30:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 16 14:30:45 volumio volumio-remote-updater[604]: [2025-09-16 14:30:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 16 14:30:45 volumio systemd[1]: Unit volumio.service entered failed state. Sep 16 14:30:45 volumio systemd[1]: Starting dynamicswap service... Sep 16 14:30:45 volumio systemd[1]: Started dynamicswap service. Sep 16 14:30:45 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Sep 16 14:30:45 volumio systemd[1]: Starting dynamicswap service... Sep 16 14:30:45 volumio systemd[1]: Started dynamicswap service. Sep 16 14:30:45 volumio systemd[1]: Stopping Volumio Backend Module... Sep 16 14:30:45 volumio systemd[1]: Starting Volumio Backend Module... Sep 16 14:30:45 volumio systemd[1]: Started Volumio Backend Module. Sep 16 14:30:46 volumio dhcpcd[622]: eth0: leased 192.168.2.231 for 7200 seconds Sep 16 14:30:46 volumio avahi-daemon[617]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.231. Sep 16 14:30:46 volumio dhcpcd[622]: eth0: adding route to 192.168.2.0/24 Sep 16 14:30:46 volumio avahi-daemon[617]: New relevant interface eth0.IPv4 for mDNS. Sep 16 14:30:46 volumio dhcpcd[622]: eth0: adding default route via 192.168.2.90 Sep 16 14:30:46 volumio avahi-daemon[617]: Registering new address record for 192.168.2.231 on eth0.IPv4. Sep 16 14:30:46 volumio snapclient[4830]: Connected to 192.168.2.231 Sep 16 14:30:46 volumio snapserver[4693]: StreamServer::NewConnection: ::ffff:192.168.2.231 Sep 16 14:30:46 volumio snapserver[4693]: Session inactive. Removing Sep 16 14:30:47 volumio ntpd[800]: Listen normally on 9 eth0 192.168.2.231 UDP 123 Sep 16 14:30:47 volumio ntpd[800]: peers refreshed Sep 16 14:30:50 volumio volumio[4890]: info: ------------------------------------------- Sep 16 14:30:50 volumio volumio[4890]: info: ----- Volumio2 ---- Sep 16 14:30:50 volumio volumio[4890]: info: ------------------------------------------- Sep 16 14:30:50 volumio volumio[4890]: info: ----- System startup ---- Sep 16 14:30:50 volumio volumio[4890]: info: ------------------------------------------- Sep 16 14:30:50 volumio volumio[4890]: info: Plugin folders cleanup Sep 16 14:30:50 volumio volumio[4890]: info: Scanning into folder /volumio/app/plugins/ Sep 16 14:30:50 volumio volumio[4890]: info: Scanning category audio_interface Sep 16 14:30:50 volumio volumio[4890]: info: Scanning category miscellanea Sep 16 14:30:50 volumio volumio[4890]: info: Scanning category music_service Sep 16 14:30:50 volumio volumio[4890]: info: Scanning category plugins.json Sep 16 14:30:50 volumio volumio[4890]: info: Scanning category system_controller Sep 16 14:30:50 volumio volumio[4890]: info: Scanning category user_interface Sep 16 14:30:50 volumio volumio[4890]: info: Scanning into folder /data/plugins/ Sep 16 14:30:50 volumio volumio[4890]: info: Scanning category miscellanea Sep 16 14:30:50 volumio volumio[4890]: info: Plugin folders cleanup completed Sep 16 14:30:50 volumio volumio[4890]: info: Loading plugins from folder /volumio/app/plugins/ Sep 16 14:30:50 volumio volumio[4890]: info: Loading plugins from folder /data/plugins/ Sep 16 14:30:50 volumio volumio[4890]: info: Loading plugin "system"... Sep 16 14:30:50 volumio volumio[4890]: info: Loading plugin "appearance"... Sep 16 14:30:50 volumio volumio-remote-updater[604]: [2025-09-16 14:30:50] [connect] Successful connection Sep 16 14:30:52 volumio volumio[4890]: info: Loading plugin "network"... Sep 16 14:30:52 volumio volumio[4890]: info: Loading plugin "services"... Sep 16 14:30:52 volumio volumio[4890]: info: Loading plugin "alsa_controller"... Sep 16 14:30:52 volumio volumio[4890]: info: Loading plugin "wizard"... Sep 16 14:30:52 volumio volumio[4890]: info: Loading plugin "volumio_command_line_client"... Sep 16 14:30:52 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:52 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:52 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:52 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:52 volumio volumio[4890]: info: Loading plugin "upnp"... Sep 16 14:30:52 volumio volumio[4890]: info: [1758033052927] Starting Upmpd Daemon Sep 16 14:30:52 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 14:30:52 volumio volumio[4890]: info: Loading plugin "mpd"... Sep 16 14:30:53 volumio volumio[4890]: info: Loading plugin "upnp_browser"... Sep 16 14:30:54 volumio volumio[4890]: info: Loading plugin "networkfs"... Sep 16 14:30:54 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 14:30:54 volumio volumio[4890]: info: Loading plugin "alarm-clock"... Sep 16 14:30:54 volumio sudo[4952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //SERVER5/music2 /mnt/NAS/music2 Sep 16 14:30:54 volumio sudo[4952]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:54 volumio volumio[4890]: info: Loading plugin "airplay_emulation"... Sep 16 14:30:54 volumio volumio[4890]: info: [1758033054939] Starting Shairport Sync Sep 16 14:30:54 volumio volumio[4890]: info: Loading plugin "last_100"... Sep 16 14:30:54 volumio volumio[4890]: info: Loading plugin "webradio"... Sep 16 14:30:54 volumio volumio[4890]: info: Loading plugin "i2s_dacs"... Sep 16 14:30:55 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 14:30:55 volumio volumio[4890]: info: Loading plugin "volumiodiscovery"... Sep 16 14:30:55 volumio volumio[4890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:55 volumio node[4890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:55 volumio node[4890]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:55 volumio node[4890]: *** WARNING *** For more information see Sep 16 14:30:55 volumio node[4890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:55 volumio node[4890]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:55 volumio node[4890]: *** WARNING *** For more information see Sep 16 14:30:55 volumio volumio[4890]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:55 volumio volumio[4890]: *** WARNING *** For more information see Sep 16 14:30:55 volumio volumio[4890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 16 14:30:55 volumio volumio[4890]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 16 14:30:55 volumio volumio[4890]: *** WARNING *** For more information see Sep 16 14:30:55 volumio volumio[4890]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 16 14:30:55 volumio volumio[4890]: Discovery: StartAdv! undefined Sep 16 14:30:55 volumio volumio[4890]: Discovery: Started advertising... Volumio - undefined Sep 16 14:30:55 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 14:30:55 volumio volumio[4890]: info: Loading plugin "albumart"... Sep 16 14:30:55 volumio volumio[4890]: info: Plugin example_plugin is not enabled Sep 16 14:30:55 volumio volumio[4890]: info: Loading plugin "updater_comm"... Sep 16 14:30:55 volumio volumio[4890]: info: Plugin mpdemulation is not enabled Sep 16 14:30:55 volumio volumio[4890]: info: Loading plugin "rest_api"... Sep 16 14:30:55 volumio volumio[4890]: info: Loading plugin "websocket"... Sep 16 14:30:55 volumio volumio[4890]: info: Loading plugin "snapcast"... Sep 16 14:30:56 volumio dhcpcd[622]: eth0: carrier lost Sep 16 14:30:56 volumio avahi-daemon[617]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 16 14:30:56 volumio avahi-daemon[617]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.2.231. Sep 16 14:30:56 volumio avahi-daemon[617]: Withdrawing address record for 192.168.2.231 on eth0. Sep 16 14:30:56 volumio avahi-daemon[617]: Withdrawing workstation service for eth0. Sep 16 14:30:56 volumio kernel: usb 1-1: USB disconnect, device number 24 Sep 16 14:30:56 volumio kernel: usb 1-1.1: USB disconnect, device number 25 Sep 16 14:30:56 volumio kernel: smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet Sep 16 14:30:56 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:56 volumio systemd[1]: Stopping ifup for eth0... Sep 16 14:30:57 volumio volumio[4890]: info: SnapCast initiated Sep 16 14:30:57 volumio volumio[4890]: info: ___________ START PLUGINS ___________ Sep 16 14:30:57 volumio volumio[4890]: info: PLUGIN START: appearance Sep 16 14:30:57 volumio volumio[4890]: info: PLUGIN START: wizard Sep 16 14:30:57 volumio volumio[4890]: info: PLUGIN START: upnp_browser Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 14:30:57 volumio dhcpcd[5013]: sending commands to master dhcpcd process Sep 16 14:30:57 volumio ifdown[4994]: dhcpcd[5013]: sending commands to master dhcpcd process Sep 16 14:30:57 volumio volumio[4890]: info: [1758033057082] CoreMusicLibrary::Adding element Media Servers Sep 16 14:30:57 volumio dhcpcd[622]: eth0: deleting route to 192.168.2.0/24 Sep 16 14:30:57 volumio dhcpcd[622]: eth0: deleting default route via 192.168.2.90 Sep 16 14:30:57 volumio volumio[4890]: info: PLUGIN START: last_100 Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 14:30:57 volumio volumio[4890]: info: [1758033057134] CoreMusicLibrary::Adding element Last_100 Sep 16 14:30:57 volumio volumio[4890]: info: PLUGIN START: webradio Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 14:30:57 volumio volumio[4890]: info: [1758033057142] CoreMusicLibrary::Adding element Webradio Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 14:30:57 volumio volumio[4890]: info: PLUGIN START: snapcast Sep 16 14:30:57 volumio volumio[4890]: info: Starting SnapCast services... Sep 16 14:30:57 volumio ifdown[4994]: Cannot find device "eth0" Sep 16 14:30:57 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Sep 16 14:30:57 volumio volumio[4890]: info: Loading i18n strings for locale en Sep 16 14:30:57 volumio volumio[4890]: Updating browse sources language Sep 16 14:30:57 volumio volumio[4890]: Cannot find translation for sourceMedia Servers Sep 16 14:30:57 volumio systemd[1]: Stopped ifup for eth0. Sep 16 14:30:57 volumio sudo[5034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapserver Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::initPlayerControls Sep 16 14:30:57 volumio sudo[5034]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:57 volumio dhcpcd[622]: eth0: removing interface Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 16 14:30:57 volumio kernel: usb 1-1: new high-speed USB device number 26 using dwc_otg Sep 16 14:30:57 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 14:30:57 volumio systemd[1]: Stopping Snapcast server... Sep 16 14:30:57 volumio snapserver[4693]: Received SIGTERM signal. Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 14:30:57 volumio snapserver[4693]: Exception in StreamSession::reader(): read_some: End of file Sep 16 14:30:57 volumio volumio[4890]: info: BOOT COMPLETED Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 14:30:57 volumio snapserver[4693]: daemon terminated. Sep 16 14:30:57 volumio systemd[1]: Starting Snapcast server... Sep 16 14:30:57 volumio snapserver[5074]: Settings file: "/var/lib/snapserver/server.json" Sep 16 14:30:57 volumio snapserver[5074]: pipe:///tmp/snapfifo?name=VOLUMIO-MPD&mode=read Sep 16 14:30:57 volumio snapserver[5074]: 2025-09-16 14-30-57 [Notice] Settings file: "/var/lib/snapserver/server.json" Sep 16 14:30:57 volumio snapserver[5078]: daemon started Sep 16 14:30:57 volumio systemd[1]: Started Snapcast server. Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::Close All Modals sent Sep 16 14:30:57 volumio sudo[5034]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:57 volumio volumio[4890]: Express server listening on port 3000 Sep 16 14:30:57 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514 Sep 16 14:30:57 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:57 volumio kernel: hub 1-1:1.0: USB hub found Sep 16 14:30:57 volumio kernel: hub 1-1:1.0: 5 ports detected Sep 16 14:30:57 volumio dhcpcd[622]: control command: dhcpcd -k eth0 Sep 16 14:30:57 volumio volumio[4890]: Volumio Calling Home Sep 16 14:30:57 volumio volumio[4890]: info: CoreStateMachine::resetVolumioState Sep 16 14:30:57 volumio volumio[4890]: info: CoreStateMachine::getcurrentVolume Sep 16 14:30:57 volumio volumio[4890]: info: CoreCommandRouter::volumioRetrievevolume Sep 16 14:30:57 volumio volumio[4890]: info: CoreStateMachine::updateTrackBlock Sep 16 14:30:57 volumio volumio[4890]: info: CorePlayQueue::getTrackBlock Sep 16 14:30:57 volumio kernel: usb 1-1.1: new high-speed USB device number 27 using dwc_otg Sep 16 14:30:58 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 Sep 16 14:30:58 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Sep 16 14:30:58 volumio kernel: smsc95xx v1.0.5 Sep 16 14:30:58 volumio snapserver[5078]: ControlServer::NewConnection: ::ffff:127.0.0.1 Sep 16 14:30:58 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:57:e1:ca Sep 16 14:30:58 volumio dhcpcd[622]: eth0: adding address fe80::c281:4297:a465:28fe Sep 16 14:30:58 volumio dhcpcd[622]: if_addaddress6: Permission denied Sep 16 14:30:58 volumio systemd[1]: Starting ifup for eth0... Sep 16 14:30:58 volumio systemd[1]: Started ifup for eth0. Sep 16 14:30:58 volumio ifup[5116]: dhcpcd[5138]: sending commands to master dhcpcd process Sep 16 14:30:58 volumio dhcpcd[5138]: sending commands to master dhcpcd process Sep 16 14:30:58 volumio dhcpcd[622]: eth0: waiting for carrier Sep 16 14:30:58 volumio dhcpcd[622]: control command: dhcpcd eth0 Sep 16 14:30:58 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Sep 16 14:30:58 volumio volumio[4890]: info: Setting Device type: Raspberry PI Sep 16 14:30:58 volumio volumio[4890]: info: snapserver started Sep 16 14:30:58 volumio volumio[4890]: info: MPD running with PID724 ,establishing connection Sep 16 14:30:58 volumio sudo[5178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart snapclient Sep 16 14:30:58 volumio sudo[5178]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:30:58 volumio snapclient[4830]: Received SIGTERM signal. Sep 16 14:30:58 volumio systemd[1]: Stopping Snapcast client... Sep 16 14:30:59 volumio systemd[1]: Reloading OpenBSD Secure Shell server. Sep 16 14:30:59 volumio snapclient[4830]: daemon terminated. Sep 16 14:30:59 volumio systemd[1]: Starting Snapcast client... Sep 16 14:30:59 volumio snapclient[5212]: daemon started Sep 16 14:30:59 volumio systemd[1]: Started Snapcast client. Sep 16 14:30:59 volumio sudo[5178]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:59 volumio sshd[803]: Received SIGHUP; restarting. Sep 16 14:30:59 volumio systemd[1]: Reloaded OpenBSD Secure Shell server. Sep 16 14:30:59 volumio snapclient[5212]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:30:59 volumio snapclient[5212]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:30:59 volumio sshd[803]: Server listening on 0.0.0.0 port 22. Sep 16 14:30:59 volumio sshd[803]: Server listening on :: port 22. Sep 16 14:30:59 volumio volumio[4890]: info: VolumeController:: Volume=10 Mute =false Sep 16 14:30:59 volumio volumio[4890]: info: CoreStateMachine::pushState Sep 16 14:30:59 volumio volumio[4890]: info: CoreStateMachine::getState Sep 16 14:30:59 volumio volumio[4890]: info: CorePlayQueue::getTrack 0 Sep 16 14:30:59 volumio volumio[4890]: info: CoreCommandRouter::volumioPushState Sep 16 14:30:59 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:30:59 volumio volumio[4890]: info: interfaceApi::pushState Sep 16 14:30:59 volumio volumio[4890]: info: Sep 16 14:30:59 volumio volumio[4890]: ---------------------------- Client requests Volume 10 Sep 16 14:30:59 volumio volumio[4890]: info: VolumeController::SetAlsaVolume10 Sep 16 14:30:59 volumio volumio[4890]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} Sep 16 14:30:59 volumio sudo[4952]: pam_unix(sudo:session): session closed for user root Sep 16 14:30:59 volumio volumio[4890]: info: snapclient started Sep 16 14:31:00 volumio dhcpcd[622]: eth0: carrier acquired Sep 16 14:31:00 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Sep 16 14:31:00 volumio dhcpcd[622]: eth0: IAID eb:57:e1:ca Sep 16 14:31:00 volumio snapclient[5212]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:31:00 volumio snapclient[5212]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:31:00 volumio dhcpcd[622]: eth0: rebinding lease of 192.168.2.231 Sep 16 14:31:00 volumio dhcpcd[622]: eth0: soliciting an IPv6 router Sep 16 14:31:00 volumio volumio[4890]: info: Reloading queue from file Sep 16 14:31:00 volumio volumio[4890]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:31:00 volumio snapserver[5078]: Exception in ControlSession::reader(): read_until: End of file Sep 16 14:31:00 volumio volumio[4890]: info: Sep 16 14:31:00 volumio volumio[4890]: null Sep 16 14:31:00 volumio volumio[4890]: info: VolumeController::Volume 10 Sep 16 14:31:00 volumio volumio[4890]: info: CoreStateMachine::pushState Sep 16 14:31:00 volumio volumio[4890]: info: CoreStateMachine::getState Sep 16 14:31:00 volumio volumio[4890]: info: CorePlayQueue::getTrack 0 Sep 16 14:31:00 volumio volumio[4890]: info: CoreCommandRouter::volumioPushState Sep 16 14:31:00 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:31:00 volumio volumio[4890]: info: interfaceApi::pushState Sep 16 14:31:00 volumio volumio[4890]: info: Cannot mount NAS music2 at system boot, trial number 1 ,retrying in 5 seconds Sep 16 14:31:00 volumio volumio[4890]: info: Setting volume on startup at 10 Sep 16 14:31:00 volumio volumio[4890]: info: CoreStateMachine::setRepeat true single undefined Sep 16 14:31:00 volumio volumio[4890]: info: CoreStateMachine::pushState Sep 16 14:31:00 volumio volumio[4890]: info: CoreStateMachine::getState Sep 16 14:31:00 volumio volumio[4890]: info: CorePlayQueue::getTrack 0 Sep 16 14:31:00 volumio volumio[4890]: info: CoreCommandRouter::volumioPushState Sep 16 14:31:00 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:31:00 volumio volumio[4890]: info: interfaceApi::pushState Sep 16 14:31:00 volumio volumio[4890]: info: CoreStateMachine::setRandom true Sep 16 14:31:00 volumio volumio[4890]: info: CoreStateMachine::pushState Sep 16 14:31:00 volumio volumio[4890]: info: CoreStateMachine::getState Sep 16 14:31:00 volumio volumio[4890]: info: CorePlayQueue::getTrack 0 Sep 16 14:31:00 volumio volumio[4890]: info: CoreCommandRouter::volumioPushState Sep 16 14:31:00 volumio volumio[4890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 14:31:00 volumio volumio[4890]: info: interfaceApi::pushState Sep 16 14:31:00 volumio volumio[4890]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:31:00 volumio volumio[4890]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:31:01 volumio volumio[4890]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:31:01 volumio volumio[4890]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/music2/_38 Special/Rock & Roll Strategy/06 Second Chance.m4a","favourite":false} Sep 16 14:31:01 volumio ntpd[800]: Deleting interface #9 eth0, 192.168.2.231#123, interface stats: received=4, sent=4, dropped=0, active_time=14 secs Sep 16 14:31:01 volumio ntpd[800]: 172.234.44.141 interface 192.168.2.231 -> (none) Sep 16 14:31:01 volumio ntpd[800]: 193.29.63.226 interface 192.168.2.231 -> (none) Sep 16 14:31:01 volumio ntpd[800]: 198.137.202.56 interface 192.168.2.231 -> (none) Sep 16 14:31:01 volumio ntpd[800]: 162.244.81.139 interface 192.168.2.231 -> (none) Sep 16 14:31:01 volumio ntpd[800]: peers refreshed Sep 16 14:31:01 volumio snapclient[5212]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:31:01 volumio snapclient[5212]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:31:01 volumio volumio[4890]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:31:01 volumio volumio[4890]: info: mDNS: A device disapperared from network Sep 16 14:31:01 volumio volumio[4890]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 16 14:31:01 volumio sudo[5235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Sep 16 14:31:01 volumio sudo[5235]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:31:01 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Sep 16 14:31:01 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Sep 16 14:31:01 volumio systemd[1]: Started ShairportSync AirTunes receiver. Sep 16 14:31:01 volumio sudo[5235]: pam_unix(sudo:session): session closed for user root Sep 16 14:31:01 volumio shairport-sync[5241]: Successful Startup Sep 16 14:31:02 volumio volumio[4890]: info: mDNS Browse raised the following error Error: index has no corresponding interface Sep 16 14:31:02 volumio volumio[4890]: info: mDNS: A device disapperared from network Sep 16 14:31:02 volumio volumio[4890]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Sep 16 14:31:02 volumio volumio[4890]: info: [1758033062224] Shairport-Sync Started Sep 16 14:31:02 volumio snapclient[5212]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:31:02 volumio snapclient[5212]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:31:02 volumio volumio[4890]: Error adding Membership: Error: addMembership EINVAL Sep 16 14:31:02 volumio snapserver[5078]: ControlServer::NewConnection: ::ffff:127.0.0.1 Sep 16 14:31:02 volumio snapserver[5078]: Session inactive. Removing Sep 16 14:31:02 volumio snapserver[5078]: Exception in ControlSession::reader(): read_until: End of file Sep 16 14:31:02 volumio volumio[4890]: info: CoreCommandRouter::volumioGetState Sep 16 14:31:02 volumio volumio[4890]: info: CoreStateMachine::getState Sep 16 14:31:02 volumio volumio[4890]: info: CorePlayQueue::getTrack 0 Sep 16 14:31:02 volumio volumio[4890]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 14:31:02 volumio volumio[4890]: { Error: addMembership ENODEV Sep 16 14:31:02 volumio volumio[4890]: at exports._errnoException (util.js:1018:11) Sep 16 14:31:02 volumio volumio[4890]: at Socket.addMembership (dgram.js:506:11) Sep 16 14:31:02 volumio volumio[4890]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:251:16) Sep 16 14:31:02 volumio volumio[4890]: at ontimeout (timers.js:386:14) Sep 16 14:31:02 volumio volumio[4890]: at tryOnTimeout (timers.js:250:5) Sep 16 14:31:02 volumio volumio[4890]: at Timer.listOnTimeout (timers.js:214:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Sep 16 14:31:02 volumio volumio[4890]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 14:31:02 volumio volumio-remote-updater[604]: [2025-09-16 14:31:02] [connect] Successful connection Sep 16 14:31:03 volumio snapclient[5212]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:31:03 volumio snapclient[5212]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:31:04 volumio snapclient[5212]: Exception in Controller::worker(): connect: Network is unreachable Sep 16 14:31:04 volumio snapclient[5212]: Error in socket shutdown: Transport endpoint is not connected Sep 16 14:31:04 volumio sudo[5265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 14:30 Sep 16 14:31:04 volumio sudo[5265]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 14:31:04 volumio dhcpcd[622]: eth0: leased 192.168.2.231 for 7200 seconds Sep 16 14:31:04 volumio dhcpcd[622]: eth0: adding route to 192.168.2.0/24 Sep 16 14:31:04 volumio dhcpcd[622]: eth0: adding default route via 192.168.2.90 Sep 16 14:31:04 volumio avahi-daemon[617]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.231. Sep 16 14:31:04 volumio avahi-daemon[617]: New relevant interface eth0.IPv4 for mDNS. Sep 16 14:31:04 volumio avahi-daemon[617]: Registering new address record for 192.168.2.231 on eth0.IPv4. PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="81a964334fe5781390903123bd0394ca968a5af9" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="10851cfc3d3feffddf9409d4c83bf0c8912f6a09" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 13 22:31:45 CET 2018" VOLUMIO_VERSION="2.378" VOLUMIO_HARDWARE="pi"