-- Logs begin at Thu 2025-02-20 16:01:23 CST, end at Thu 2025-02-20 16:03:20 CST. -- Feb 20 16:02:00 volumio volumio[1077]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 20 16:02:03 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 20 16:02:03 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 20 16:02:03 volumio volumio[1077]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 32 Feb 20 16:02:04 volumio volumio[1077]: info: CoreCommandRouter::volumioGetState Feb 20 16:02:04 volumio volumio[1077]: info: CorePlayQueue::getTrack 0 Feb 20 16:02:07 volumio systemd[1]: systemd-timedated.service: Succeeded. Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 20 16:02:08 volumio volumio-remote-updater[806]: No test mode Feb 20 16:02:08 volumio volumio-remote-updater[806]: No alpha test mode Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::volumioGetBrowseSources Feb 20 16:02:08 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 20 16:02:09 volumio volumio[1077]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 20 16:02:09 volumio volumio[1077]: info: Completed starting MyVolumio Plugin Feb 20 16:02:09 volumio volumio[1077]: [Metrics] CommandRouter: 33s 377.67ms Feb 20 16:02:09 volumio volumio[1077]: info: CoreCommandRouter::volumiosetStartupVolume Feb 20 16:02:09 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 20 16:02:09 volumio volumio[1077]: info: VolumeController:: Setting startup Volume 50 Feb 20 16:02:09 volumio volumio[1077]: info: VolumeController::SetAlsaVolume50 Feb 20 16:02:09 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 20 16:02:09 volumio volumio[1077]: info: CoreCommandRouter::Close All Modals sent Feb 20 16:02:09 volumio volumio[1077]: info: CoreCommandRouter::Close All Modals sent Feb 20 16:02:09 volumio volumio[1077]: info: CoreStateMachine::pushState Feb 20 16:02:09 volumio volumio[1077]: info: CorePlayQueue::getTrack 0 Feb 20 16:02:09 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 20 16:02:09 volumio volumio[1077]: info: CoreCommandRouter::volumioPushState Feb 20 16:02:09 volumio volumio[1077]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Feb 20 16:02:09 volumio volumio[1077]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 20 16:02:09 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 20 16:02:10 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 20 16:02:10 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 20 16:02:10 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 20 16:02:12 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 20 16:02:12 volumio volumio[1077]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 20 16:02:12 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 20 16:02:12 volumio volumio[1077]: info: Received Get System Version Feb 20 16:02:12 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 20 16:02:12 volumio volumio[1077]: info: Received Get System Info Feb 20 16:02:12 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 20 16:02:12 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 20 16:02:12 volumio volumio[1077]: info: Discovery: Getting this device information Feb 20 16:02:12 volumio volumio[1077]: info: CoreCommandRouter::volumioGetState Feb 20 16:02:12 volumio volumio[1077]: info: CorePlayQueue::getTrack 0 Feb 20 16:02:12 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 20 16:02:16 volumio volumio[1077]: info: BOOT COMPLETED Feb 20 16:02:32 volumio ntpd[975]: Soliciting pool server 220.134.230.37 Feb 20 16:02:32 volumio ntpd[975]: Soliciting pool server 218.35.163.245 Feb 20 16:02:32 volumio ntpd[975]: Soliciting pool server 218.35.163.245 Feb 20 16:02:33 volumio ntpd[975]: Soliciting pool server 114.35.131.27 Feb 20 16:02:34 volumio ntpd[975]: Soliciting pool server 220.135.175.159 Feb 20 16:02:34 volumio ntpd[975]: Soliciting pool server 220.134.160.116 Feb 20 16:02:34 volumio ntpd[975]: Soliciting pool server 118.163.81.63 Feb 20 16:02:35 volumio ntpd[975]: Soliciting pool server 122.117.253.246 Feb 20 16:02:35 volumio ntpd[975]: Soliciting pool server 118.163.81.61 Feb 20 16:02:36 volumio ntpd[975]: Soliciting pool server 114.34.171.136 Feb 20 16:02:37 volumio ntpd[975]: Soliciting pool server 2406:2000:fc:437::2000 Feb 20 16:02:39 volumio ntpd[975]: receive: Unexpected origin timestamp 0xeb61601e.b26b44ba does not match aorg 0000000000.00000000 from server@114.34.171.136 xmt 0xeb61601f.444e0d56 Feb 20 16:02:39 volumio ntpd[975]: receive: Unexpected origin timestamp 0xeb61601e.b26cf417 does not match aorg 0000000000.00000000 from server@118.163.81.63 xmt 0xeb61601f.448e817a Feb 20 16:02:42 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 20 16:02:42 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 20 16:02:50 volumio volumio[1077]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 20 16:02:55 volumio volumio[1077]: info: CALLMETHOD: user_interface touch_display saveScreensaverConf [object Object] Feb 20 16:02:55 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScreensaverConf Feb 20 16:02:55 volumio volumio[1077]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 20 16:03:03 volumio volumio[1077]: info: CALLMETHOD: user_interface touch_display saveScreensaverConf [object Object] Feb 20 16:03:03 volumio volumio[1077]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScreensaverConf Feb 20 16:03:03 volumio volumio[1077]: info: CoreCommandRouter::volumioGetState Feb 20 16:03:03 volumio volumio[1077]: info: CorePlayQueue::getTrack 0 Feb 20 16:03:03 volumio volumio[1077]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 20 16:03:06 volumio volumio[1077]: info: CoreCommandRouter::volumioGetState Feb 20 16:03:06 volumio volumio[1077]: info: CorePlayQueue::getTrack 0 Feb 20 16:03:20 volumio volumio[1077]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Feb 20 16:03:20 volumio volumio[1077]: info: PLUGIN onReboot : networkfs Feb 20 16:03:20 volumio volumio[1077]: info: PLUGIN onReboot : audiophonicsonoff Feb 20 16:03:20 volumio volumio[1077]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 20 16:03:20 volumio volumio[1077]: TypeError: Cannot read property 'writeSync' of undefined Feb 20 16:03:20 volumio volumio[1077]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Feb 20 16:03:20 volumio volumio[1077]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Feb 20 16:03:20 volumio volumio[1077]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Feb 20 16:03:20 volumio volumio[1077]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 20 16:03:20 volumio volumio[1077]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 20 16:03:20 volumio volumio[1077]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Feb 20 16:03:20 volumio volumio[1077]: at CoreCommandRouter.reboot (/volumio/app/index.js:1344:22) Feb 20 16:03:20 volumio volumio[1077]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Feb 20 16:03:20 volumio volumio[1077]: at Socket.emit (events.js:315:20) Feb 20 16:03:20 volumio volumio[1077]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 20 16:03:20 volumio volumio[1077]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 20 16:03:20 volumio volumio[1077]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 20 16:03:20 volumio sudo[2302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-20 16:02 Feb 20 16:03:20 volumio sudo[2302]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"