-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2025-05-04 16:29:01 CEST. -- May 04 16:28:02 volumio ntpd[793]: Soliciting pool server 129.250.35.251 May 04 16:28:02 volumio ntpd[793]: Soliciting pool server 82.64.230.205 May 04 16:28:02 volumio volumio[1009]: info: MYVOLUMIO: Adding device May 04 16:28:02 volumio volumio[1009]: info: MYVOLUMIO: Evaluating Server May 04 16:28:03 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 16:28:03 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 16:28:03 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 04 16:28:03 volumio volumio-remote-updater[634]: No test mode May 04 16:28:03 volumio volumio-remote-updater[634]: No alpha test mode May 04 16:28:03 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 04 16:28:03 volumio ntpd[793]: Soliciting pool server 162.159.200.1 May 04 16:28:03 volumio ntpd[793]: Soliciting pool server 82.64.81.218 May 04 16:28:03 volumio volumio[1009]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 04 16:28:03 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 04 16:28:03 volumio volumio[1009]: info: Setting Geolocation for MyVolumio to eu3 May 04 16:28:03 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 16:28:03 volumio volumio[1009]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 04 16:28:04 volumio ntpd[793]: Soliciting pool server 5.189.185.174 May 04 16:28:04 volumio ntpd[793]: Soliciting pool server 5.39.80.51 May 04 16:28:04 volumio ntpd[793]: Soliciting pool server 195.154.226.102 May 04 16:28:04 volumio volumio[1009]: info: Updating MyVolumio device info May 04 16:28:04 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 16:28:04 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 16:28:04 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 16:28:04 volumio volumio[1009]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 04 16:28:05 volumio ntpd[793]: Soliciting pool server 217.182.137.208 May 04 16:28:05 volumio ntpd[793]: Soliciting pool server 82.66.40.79 May 04 16:28:05 volumio ntpd[793]: Soliciting pool server 82.64.247.11 May 04 16:28:06 volumio ntpd[793]: Soliciting pool server 82.67.62.62 May 04 16:28:06 volumio ntpd[793]: Soliciting pool server 195.83.132.135 May 04 16:28:06 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 16:28:06 volumio volumio[1009]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 04 16:28:06 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState May 04 16:28:06 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 May 04 16:28:07 volumio ntpd[793]: Soliciting pool server 82.67.41.119 May 04 16:28:07 volumio ntpd[793]: Soliciting pool server 51.75.17.219 May 04 16:28:08 volumio ntpd[793]: Soliciting pool server 82.67.126.242 May 04 16:28:08 volumio ntpd[793]: Soliciting pool server 2001:41d0:305:2100::621 May 04 16:28:09 volumio ntpd[793]: Soliciting pool server 45.132.96.81 May 04 16:28:11 volumio ntpd[793]: receive: Unexpected origin timestamp 0xebc1f7fb.3ca01e37 does not match aorg 0000000000.00000000 from server@82.67.126.242 xmt 0xebc1f7fb.c6d6f097 May 04 16:28:11 volumio ntpd[793]: receive: Unexpected origin timestamp 0xebc1f7fb.3cddd31b does not match aorg 0000000000.00000000 from server@82.64.230.205 xmt 0xebc1f7fb.c7759b35 May 04 16:28:11 volumio ntpd[793]: receive: Unexpected origin timestamp 0xebc1f7fb.3caec52b does not match aorg 0000000000.00000000 from server@51.75.17.219 xmt 0xebc1f7fb.c7476b41 May 04 16:28:11 volumio ntpd[793]: receive: Unexpected origin timestamp 0xebc1f7fb.3ccc7ec8 does not match aorg 0000000000.00000000 from server@5.39.80.51 xmt 0xebc1f7fb.c78f219d May 04 16:28:11 volumio ntpd[793]: receive: Unexpected origin timestamp 0xebc1f7fb.3cd79b00 does not match aorg 0000000000.00000000 from server@82.64.81.218 xmt 0xebc1f7fb.c84ac55a May 04 16:28:11 volumio ntpd[793]: receive: Unexpected origin timestamp 0xebc1f7fb.3cb6d697 does not match aorg 0000000000.00000000 from server@195.83.132.135 xmt 0xebc1f7fb.c8d88800 May 04 16:28:11 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 16:28:11 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 16:28:11 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 04 16:28:11 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 04 16:28:11 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 04 16:28:11 volumio volumio[1009]: info: CoreCommandRouter::volumioGetBrowseSources May 04 16:28:11 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 16:28:15 volumio volumio[1009]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 04 16:28:15 volumio volumio[1009]: info: Completed starting MyVolumio Plugin May 04 16:28:15 volumio volumio[1009]: [Metrics] CommandRouter: 56s 928.82ms May 04 16:28:15 volumio volumio[1009]: info: CoreCommandRouter::volumiosetStartupVolume May 04 16:28:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 04 16:28:15 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 04 16:28:15 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent May 04 16:28:15 volumio volumio[1009]: info: CoreCommandRouter::Close All Modals sent May 04 16:28:16 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 04 16:28:16 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 04 16:28:16 volumio volumio[1009]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 04 16:28:22 volumio volumio[1009]: info: BOOT COMPLETED May 04 16:28:26 volumio volumio[1360]: ......................................................................................................................................................................................................++++ May 04 16:28:44 volumio volumio[1360]: ................................................................................++++ May 04 16:28:44 volumio volumio[1360]: e is 65537 (0x010001) May 04 16:28:44 volumio volumio[1360]: writing RSA key May 04 16:28:57 volumio volumio[1009]: info: CoreCommandRouter::volumioGetState May 04 16:28:57 volumio volumio[1009]: info: CorePlayQueue::getTrack 0 May 04 16:29:00 volumio volumio[1009]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 04 16:29:00 volumio volumio[1009]: info: PLUGIN onReboot : networkfs May 04 16:29:00 volumio volumio[1009]: info: PLUGIN onReboot : audiophonicsonoff May 04 16:29:00 volumio volumio[1009]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 16:29:00 volumio volumio[1009]: TypeError: Cannot read property 'writeSync' of undefined May 04 16:29:00 volumio volumio[1009]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) May 04 16:29:00 volumio volumio[1009]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 04 16:29:00 volumio volumio[1009]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 04 16:29:00 volumio volumio[1009]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 04 16:29:00 volumio volumio[1009]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 04 16:29:00 volumio volumio[1009]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 04 16:29:00 volumio volumio[1009]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) May 04 16:29:00 volumio volumio[1009]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) May 04 16:29:00 volumio volumio[1009]: at Socket.emit (events.js:315:20) May 04 16:29:00 volumio volumio[1009]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 04 16:29:00 volumio volumio[1009]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 04 16:29:00 volumio volumio[1009]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 16:29:01 volumio sudo[22202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 16:28 May 04 16:29:01 volumio sudo[22202]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"