-- Logs begin at Thu 2019-02-14 18:11:59 PST, end at Sun 2025-01-12 22:03:40 PST. -- Jan 12 22:02:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 22:02:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 22:02:05 volumio volumio[1109]: info: Discovery: Getting this device information Jan 12 22:02:05 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Jan 12 22:02:05 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Jan 12 22:02:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 22:02:07 volumio ntpd[844]: Soliciting pool server 222.127.1.21 Jan 12 22:02:11 volumio ntpd[844]: Soliciting pool server 222.127.1.24 Jan 12 22:02:13 volumio ntpd[844]: Soliciting pool server 222.127.1.26 Jan 12 22:02:16 volumio ntpd[844]: Soliciting pool server 2a05:f480:2c00:1d51:5400:5ff:fe04:6f3 Jan 12 22:02:35 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 22:02:35 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 22:02:35 volumio volumio[1109]: info: Discovery: Getting this device information Jan 12 22:02:35 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Jan 12 22:02:35 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Jan 12 22:02:35 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 22:03:05 volumio volumio-remote-updater[700]: zsync done Jan 12 22:03:05 volumio volumio-remote-updater[700]: PROGRESS: 80, STATUS: "Downloading new update", ETA: "2m" Jan 12 22:03:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 12 22:03:05 volumio volumio[1109]: updateProgress Jan 12 22:03:05 volumio volumio[1109]: { Jan 12 22:03:05 volumio volumio[1109]: downloadSpeed: '', Jan 12 22:03:05 volumio volumio[1109]: eta: '2m', Jan 12 22:03:05 volumio volumio[1109]: progress: 80, Jan 12 22:03:05 volumio volumio[1109]: status: 'Downloading new update' Jan 12 22:03:05 volumio volumio[1109]: } Jan 12 22:03:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 22:03:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 22:03:05 volumio volumio[1109]: info: Discovery: Getting this device information Jan 12 22:03:05 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Jan 12 22:03:05 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Jan 12 22:03:05 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 22:03:12 volumio ntpd[844]: Soliciting pool server 222.127.1.26 Jan 12 22:03:18 volumio ntpd[844]: Soliciting pool server 222.127.1.22 Jan 12 22:03:20 volumio ntpd[844]: Soliciting pool server 222.127.1.25 Jan 12 22:03:23 volumio ntpd[844]: Soliciting pool server 2400:6180:100:d0::8d9:f001 Jan 12 22:03:34 volumio volumio-remote-updater[700]: zsync done Jan 12 22:03:34 volumio volumio-remote-updater[700]: PROGRESS: 90, STATUS: "Cleaning old files", ETA: "1m" Jan 12 22:03:34 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 12 22:03:34 volumio volumio[1109]: updateProgress Jan 12 22:03:34 volumio volumio[1109]: { Jan 12 22:03:34 volumio volumio[1109]: downloadSpeed: '', Jan 12 22:03:34 volumio volumio[1109]: eta: '1m', Jan 12 22:03:34 volumio volumio[1109]: progress: 90, Jan 12 22:03:34 volumio volumio[1109]: status: 'Cleaning old files' Jan 12 22:03:34 volumio volumio[1109]: } Jan 12 22:03:34 volumio volumio-remote-updater[700]: /bin/cp: cannot stat '/data/volumio_factory.sqsh': No such file or directory Jan 12 22:03:35 volumio volumio-remote-updater[700]: /bin/rm: cannot remove '/data/volumio_factory.sqsh': No such file or directory Jan 12 22:03:35 volumio volumio-remote-updater[700]: PROGRESS: 95, STATUS: "Finalizing update", ETA: "30s" Jan 12 22:03:35 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 12 22:03:35 volumio volumio[1109]: updateProgress Jan 12 22:03:35 volumio volumio[1109]: { Jan 12 22:03:35 volumio volumio[1109]: downloadSpeed: '', Jan 12 22:03:35 volumio volumio[1109]: eta: '30s', Jan 12 22:03:35 volumio volumio[1109]: progress: 95, Jan 12 22:03:35 volumio volumio[1109]: status: 'Finalizing update' Jan 12 22:03:35 volumio volumio[1109]: } Jan 12 22:03:35 volumio volumio-remote-updater[700]: PROGRESS: 100, STATUS: "success", MESSAGE: "Successfully updated to \"3.785\" version. System restart required." Jan 12 22:03:35 volumio volumio-remote-updater[700]: No test mode Jan 12 22:03:35 volumio volumio-remote-updater[700]: No alpha test mode Jan 12 22:03:35 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 22:03:35 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 22:03:35 volumio volumio[1109]: info: Discovery: Getting this device information Jan 12 22:03:35 volumio volumio[1109]: info: CoreCommandRouter::volumioGetState Jan 12 22:03:35 volumio volumio[1109]: info: CorePlayQueue::getTrack 0 Jan 12 22:03:35 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 22:03:36 volumio volumio-remote-updater[700]: [2025-01-12 22:03:36] [disconnect] Disconnect close local:[1000] remote:[1000] Jan 12 22:03:36 volumio volumio-remote-updater[700]: [2025-01-12 22:03:36] [info] asio async_write error: system:32 (Broken pipe) Jan 12 22:03:36 volumio volumio-remote-updater[700]: [2025-01-12 22:03:36] [fatal] handle_write_frame error: websocketpp.transport:2 (Underlying Transport Error) Jan 12 22:03:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 12 22:03:36 volumio volumio[1109]: updateReady Jan 12 22:03:36 volumio volumio[1109]: { Jan 12 22:03:36 volumio volumio[1109]: changeLogLink: 'http://volumio.org', Jan 12 22:03:36 volumio volumio[1109]: description: '

FIXES

 

NEW ADDITIONS

 

', Jan 12 22:03:36 volumio volumio[1109]: title: 'Update v3.785', Jan 12 22:03:36 volumio volumio[1109]: updateavailable: true Jan 12 22:03:36 volumio volumio[1109]: } Jan 12 22:03:36 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Jan 12 22:03:36 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Jan 12 22:03:36 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Jan 12 22:03:36 volumio systemd[1]: Stopped volumio-remote-updater.service. Jan 12 22:03:36 volumio systemd[1]: Started volumio-remote-updater.service. Jan 12 22:03:36 volumio volumio-remote-updater[5108]: Error: No active session Jan 12 22:03:36 volumio volumio-remote-updater[5108]: [2025-01-12 22:03:36] [connect] Successful connection Jan 12 22:03:36 volumio volumio-remote-updater[5108]: [2025-01-12 22:03:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736690616 101 Jan 12 22:03:36 volumio volumio[1109]: 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: 7 Jan 12 22:03:36 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 12 22:03:37 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 12 22:03:38 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 12 22:03:39 volumio volumio[1109]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jan 12 22:03:39 volumio volumio[1109]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jan 12 22:03:39 volumio volumio[1109]: info: PLUGIN onReboot : networkfs Jan 12 22:03:39 volumio volumio[1109]: info: PLUGIN onReboot : audiophonicsonoff Jan 12 22:03:39 volumio volumio[1109]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 22:03:39 volumio volumio[1109]: TypeError: Cannot read property 'writeSync' of undefined Jan 12 22:03:39 volumio volumio[1109]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Jan 12 22:03:39 volumio volumio[1109]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jan 12 22:03:39 volumio volumio[1109]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jan 12 22:03:39 volumio volumio[1109]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 12 22:03:39 volumio volumio[1109]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 12 22:03:39 volumio volumio[1109]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jan 12 22:03:39 volumio volumio[1109]: at CoreCommandRouter.reboot (/volumio/app/index.js:1345:22) Jan 12 22:03:39 volumio volumio[1109]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Jan 12 22:03:39 volumio volumio[1109]: at Socket.emit (events.js:315:20) Jan 12 22:03:39 volumio volumio[1109]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 12 22:03:39 volumio volumio[1109]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 12 22:03:39 volumio volumio[1109]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 22:03:40 volumio sudo[5134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 22:02 Jan 12 22:03:40 volumio sudo[5134]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"