-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-12-27 03:41:09 CET. -- Dec 27 03:40:02 volumio volumio-remote-updater[616]: PROGRESS: 30, STATUS: "Downloading new update", ETA: "4m" Dec 27 03:40:02 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:02 volumio volumio[1176]: updateProgress Dec 27 03:40:02 volumio volumio[1176]: { Dec 27 03:40:02 volumio volumio[1176]: downloadSpeed: '', Dec 27 03:40:02 volumio volumio[1176]: eta: '4m', Dec 27 03:40:02 volumio volumio[1176]: progress: 30, Dec 27 03:40:02 volumio volumio[1176]: status: 'Neue Aktualisierung wird heruntergeladen' Dec 27 03:40:02 volumio volumio[1176]: } Dec 27 03:40:47 volumio dhcpcd[1023]: wlan0: hardware address 00:00:00:00:00:00 claims 192.168.1.248 Dec 27 03:40:47 volumio volumio-remote-updater[616]: zsync done Dec 27 03:40:48 volumio volumio-remote-updater[616]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m" Dec 27 03:40:48 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:48 volumio volumio[1176]: updateProgress Dec 27 03:40:48 volumio volumio[1176]: { Dec 27 03:40:48 volumio volumio[1176]: downloadSpeed: '', Dec 27 03:40:48 volumio volumio[1176]: eta: '2m', Dec 27 03:40:48 volumio volumio[1176]: progress: 80, Dec 27 03:40:48 volumio volumio[1176]: status: 'Neue Aktualisierung wird heruntergeladen' Dec 27 03:40:48 volumio volumio[1176]: } Dec 27 03:40:48 volumio dhcpcd[1023]: wlan0: hardware address 00:00:00:00:00:00 claims 192.168.1.248 Dec 27 03:40:48 volumio dhcpcd[1023]: wlan0: 10 second defence failed for 192.168.1.248 Dec 27 03:40:48 volumio avahi-daemon[633]: Withdrawing address record for 192.168.1.248 on wlan0. Dec 27 03:40:48 volumio avahi-daemon[633]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.248. Dec 27 03:40:48 volumio dhcpcd[1023]: wlan0: deleting route to 192.168.1.0/24 Dec 27 03:40:48 volumio dhcpcd[1023]: wlan0: deleting default route via 192.168.1.1 Dec 27 03:40:48 volumio avahi-daemon[633]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 27 03:40:48 volumio volumio[1176]: info: Discovery: A device disappeared from network Dec 27 03:40:48 volumio volumio[1176]: info: Discovery: Device volumio disappeared from network Dec 27 03:40:48 volumio dhcpcd[1023]: wlan0: rebinding lease of 192.168.1.248 Dec 27 03:40:48 volumio dhcpcd[1023]: wlan0: probing address 192.168.1.248/24 Dec 27 03:40:50 volumio ntpd[779]: Deleting interface #12 wlan0, 192.168.1.248#123, interface stats: received=0, sent=0, dropped=0, active_time=3595 secs Dec 27 03:40:50 volumio volumio-remote-updater[616]: zsync done Dec 27 03:40:52 volumio volumio-remote-updater[616]: PROGRESS: 90, STATUS: "Cleaning old files", ETA: "1m" Dec 27 03:40:52 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:52 volumio volumio[1176]: updateProgress Dec 27 03:40:52 volumio volumio[1176]: { Dec 27 03:40:52 volumio volumio[1176]: downloadSpeed: '', Dec 27 03:40:52 volumio volumio[1176]: eta: '1m', Dec 27 03:40:52 volumio volumio[1176]: progress: 90, Dec 27 03:40:52 volumio volumio[1176]: status: 'Alte Dateien werden bereinigt' Dec 27 03:40:52 volumio volumio[1176]: } Dec 27 03:40:52 volumio volumio-remote-updater[616]: /bin/cp: cannot stat '/data/volumio_factory.sqsh': No such file or directory Dec 27 03:40:52 volumio volumio-remote-updater[616]: /bin/rm: cannot remove '/data/volumio_factory.sqsh': No such file or directory Dec 27 03:40:52 volumio volumio-remote-updater[616]: PROGRESS: 95, STATUS: "Finalizing update", ETA: "30s" Dec 27 03:40:52 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:52 volumio volumio[1176]: updateProgress Dec 27 03:40:52 volumio volumio[1176]: { Dec 27 03:40:52 volumio volumio[1176]: downloadSpeed: '', Dec 27 03:40:52 volumio volumio[1176]: eta: '30s', Dec 27 03:40:52 volumio volumio[1176]: progress: 95, Dec 27 03:40:52 volumio volumio[1176]: status: 'Aktualisierung wird abgeschlossen' Dec 27 03:40:52 volumio volumio[1176]: } Dec 27 03:40:53 volumio volumio-remote-updater[616]: PROGRESS: 100, STATUS: "success", MESSAGE: "Successfully updated to \"3.886\" version. System restart required." Dec 27 03:40:53 volumio volumio-remote-updater[616]: No test mode Dec 27 03:40:53 volumio volumio-remote-updater[616]: No alpha test mode Dec 27 03:40:53 volumio volumio-remote-updater[616]: [2025-12-27 03:40:53] [disconnect] Disconnect close local:[1000] remote:[1000] Dec 27 03:40:53 volumio volumio-remote-updater[616]: [2025-12-27 03:40:53] [info] asio async_write error: system:32 (Broken pipe) Dec 27 03:40:53 volumio volumio-remote-updater[616]: [2025-12-27 03:40:53] [fatal] handle_write_frame error: websocketpp.transport:2 (Underlying Transport Error) Dec 27 03:40:53 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:53 volumio volumio[1176]: updateReady Dec 27 03:40:53 volumio volumio[1176]: { Dec 27 03:40:53 volumio volumio[1176]: changeLogLink: 'http://volumio.org', Dec 27 03:40:53 volumio volumio[1176]: description: '

FIXES





NEW ADDITIONS



 

 

', Dec 27 03:40:53 volumio volumio[1176]: title: 'Update v3.886', Dec 27 03:40:53 volumio volumio[1176]: updateavailable: true Dec 27 03:40:53 volumio volumio[1176]: } Dec 27 03:40:53 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Dec 27 03:40:53 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Dec 27 03:40:53 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Dec 27 03:40:53 volumio systemd[1]: Stopped volumio-remote-updater.service. Dec 27 03:40:53 volumio systemd[1]: Started volumio-remote-updater.service. Dec 27 03:40:53 volumio volumio-remote-updater[31335]: Error: No active session Dec 27 03:40:53 volumio volumio-remote-updater[31335]: [2025-12-27 03:40:53] [connect] Successful connection Dec 27 03:40:53 volumio volumio[1176]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Dec 27 03:40:53 volumio volumio-remote-updater[31335]: [2025-12-27 03:40:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766803253 101 Dec 27 03:40:54 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:54 volumio dhcpcd[1023]: wlan0: leased 192.168.1.248 for 86400 seconds Dec 27 03:40:54 volumio avahi-daemon[633]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.248. Dec 27 03:40:54 volumio avahi-daemon[633]: New relevant interface wlan0.IPv4 for mDNS. Dec 27 03:40:54 volumio avahi-daemon[633]: Registering new address record for 192.168.1.248 on wlan0.IPv4. Dec 27 03:40:54 volumio dhcpcd[1023]: wlan0: adding route to 192.168.1.0/24 Dec 27 03:40:54 volumio dhcpcd[1023]: wlan0: adding default route via 192.168.1.1 Dec 27 03:40:54 volumio volumio[1176]: info: Discovery: adding 64287d1a-f5e0-4642-b497-7b326b31c132 Dec 27 03:40:54 volumio volumio[1176]: info: Discovery: Found device Volumio Dec 27 03:40:54 volumio volumio[1176]: info: CoreCommandRouter::volumioGetState Dec 27 03:40:54 volumio volumio[1176]: info: CorePlayQueue::getTrack 0 Dec 27 03:40:55 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:55 volumio ntpd[779]: Listen normally on 13 wlan0 192.168.1.248:123 Dec 27 03:40:55 volumio ntpd[779]: new interface(s) found: waking up resolver Dec 27 03:40:56 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:57 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:58 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:40:59 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:00 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:01 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:02 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:03 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:04 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:05 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:06 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:07 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:08 volumio volumio[1176]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Dec 27 03:41:09 volumio volumio[1176]: info: CoreCommandRouter::Close All Modals sent Dec 27 03:41:09 volumio volumio[1176]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Dec 27 03:41:09 volumio volumio[1176]: info: PLUGIN onReboot : networkfs Dec 27 03:41:09 volumio volumio[1176]: info: PLUGIN onReboot : audiophonicsonoff Dec 27 03:41:09 volumio volumio[1176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 03:41:09 volumio volumio[1176]: TypeError: Cannot read property 'writeSync' of undefined Dec 27 03:41:09 volumio volumio[1176]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Dec 27 03:41:09 volumio volumio[1176]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Dec 27 03:41:09 volumio volumio[1176]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Dec 27 03:41:09 volumio volumio[1176]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 27 03:41:09 volumio volumio[1176]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 27 03:41:09 volumio volumio[1176]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Dec 27 03:41:09 volumio volumio[1176]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Dec 27 03:41:09 volumio volumio[1176]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/updater_comm/index.js:156:35) Dec 27 03:41:09 volumio volumio[1176]: at listOnTimeout (internal/timers.js:554:17) Dec 27 03:41:09 volumio volumio[1176]: at processTimers (internal/timers.js:497:7) Dec 27 03:41:09 volumio volumio[1176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 03:41:09 volumio sudo[31402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-27 03:40 Dec 27 03:41:09 volumio sudo[31402]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"