-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-04-14 17:54:06 CEST. -- Apr 14 17:53:00 volumio volumio[985]: info: Successfully Updated MyVolumio device Apr 14 17:53:00 volumio volumio[1359]: ......................................................................++++ Apr 14 17:53:03 volumio volumio[985]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 14 17:53:04 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 17:53:04 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 17:53:05 volumio volumio[985]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 17:53:08 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 17:53:08 volumio volumio[985]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 14 17:53:08 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:08 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:08 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 17:53:08 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 17:53:08 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 17:53:08 volumio volumio[985]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 14 17:53:08 volumio volumio-remote-updater[591]: No test mode Apr 14 17:53:08 volumio volumio-remote-updater[591]: No alpha test mode Apr 14 17:53:08 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 14 17:53:08 volumio volumio[985]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 14 17:53:08 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 14 17:53:11 volumio ntpd[783]: Soliciting pool server 192.86.14.67 Apr 14 17:53:11 volumio volumio[985]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 17:53:11 volumio volumio[985]: info: Received Get System Info Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 17:53:11 volumio volumio[985]: info: Discovery: Getting this device information Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:11 volumio volumio[985]: info: Listing playlists Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 17:53:11 volumio volumio[985]: info: Received Get System Info Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 17:53:11 volumio volumio[985]: info: Discovery: Getting this device information Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 17:53:11 volumio volumio[985]: info: Received Get System Info Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 17:53:11 volumio volumio[985]: info: Discovery: Getting this device information Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:11 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:11 volumio volumio[985]: info: Listing playlists Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 17:53:11 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 17:53:12 volumio volumio[985]: info: Received Get System Info Apr 14 17:53:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 17:53:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 17:53:12 volumio volumio[985]: info: Discovery: Getting this device information Apr 14 17:53:12 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:12 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:12 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 17:53:12 volumio ntpd[783]: Soliciting pool server 213.222.217.11 Apr 14 17:53:12 volumio ntpd[783]: Soliciting pool server 193.70.94.182 Apr 14 17:53:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:53:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:53:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 14 17:53:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 14 17:53:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 17:53:13 volumio volumio[985]: info: CoreCommandRouter::volumioGetBrowseSources Apr 14 17:53:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 17:53:13 volumio ntpd[783]: Soliciting pool server 194.146.251.102 Apr 14 17:53:13 volumio ntpd[783]: Soliciting pool server 178.215.228.24 Apr 14 17:53:13 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 14 17:53:14 volumio ntpd[783]: Soliciting pool server 89.161.47.131 Apr 14 17:53:14 volumio ntpd[783]: Soliciting pool server 80.68.234.117 Apr 14 17:53:15 volumio ntpd[783]: Soliciting pool server 51.68.141.5 Apr 14 17:53:15 volumio ntpd[783]: Soliciting pool server 194.177.4.2 Apr 14 17:53:15 volumio ntpd[783]: Soliciting pool server 213.222.217.10 Apr 14 17:53:15 volumio volumio[985]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 14 17:53:15 volumio volumio[985]: info: Completed starting MyVolumio Plugin Apr 14 17:53:15 volumio volumio[985]: [Metrics] CommandRouter: 48s 82.43ms Apr 14 17:53:15 volumio volumio[985]: info: CoreCommandRouter::volumiosetStartupVolume Apr 14 17:53:15 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 17:53:15 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 17:53:15 volumio volumio[985]: info: CoreCommandRouter::Close All Modals sent Apr 14 17:53:15 volumio volumio[985]: info: CoreCommandRouter::Close All Modals sent Apr 14 17:53:15 volumio volumio[985]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 14 17:53:15 volumio volumio[985]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:1700:(_snd_pcm_volumioswitch_open) Failed to open control fifo Apr 14 17:53:15 volumio volumio[985]: aplay: main:828: audio open error: No such file or directory Apr 14 17:53:15 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 17:53:16 volumio ntpd[783]: Soliciting pool server 162.159.200.123 Apr 14 17:53:16 volumio ntpd[783]: Soliciting pool server 185.79.243.74 Apr 14 17:53:16 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 14 17:53:16 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 17:53:16 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 14 17:53:17 volumio ntpd[783]: Soliciting pool server 85.115.212.254 Apr 14 17:53:17 volumio ntpd[783]: Soliciting pool server 46.171.0.154 Apr 14 17:53:17 volumio volumio[1359]: .........................................................................++++ Apr 14 17:53:17 volumio volumio[1359]: e is 65537 (0x010001) Apr 14 17:53:17 volumio volumio[1359]: writing RSA key Apr 14 17:53:18 volumio ntpd[783]: Soliciting pool server 89.161.47.136 Apr 14 17:53:18 volumio ntpd[783]: Soliciting pool server 89.25.168.148 Apr 14 17:53:19 volumio ntpd[783]: Soliciting pool server 2001:678:8::123 Apr 14 17:53:21 volumio ntpd[783]: receive: Unexpected origin timestamp 0xeba7adf0.54fec890 does not match aorg 0000000000.00000000 from server@194.177.4.2 xmt 0xeba7adf1.806ec29c Apr 14 17:53:21 volumio ntpd[783]: receive: Unexpected origin timestamp 0xeba7adf0.54f52756 does not match aorg 0000000000.00000000 from server@85.115.212.254 xmt 0xeba7adf1.898ae252 Apr 14 17:53:21 volumio ntpd[783]: receive: Unexpected origin timestamp 0xeba7adf0.54fc5fac does not match aorg 0000000000.00000000 from server@213.222.217.10 xmt 0xeba7adf1.89133710 Apr 14 17:53:21 volumio ntpd[783]: receive: Unexpected origin timestamp 0xeba7adf0.5503272f does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeba7adf1.8b502514 Apr 14 17:53:23 volumio volumio[985]: info: BOOT COMPLETED Apr 14 17:53:25 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 17:53:25 volumio volumio[985]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 17:53:25 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 17:53:25 volumio volumio[985]: info: Received Get System Version Apr 14 17:53:25 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 17:53:25 volumio volumio[985]: info: Received Get System Info Apr 14 17:53:25 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 17:53:25 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 17:53:25 volumio volumio[985]: info: Discovery: Getting this device information Apr 14 17:53:25 volumio volumio[985]: info: CoreCommandRouter::volumioGetState Apr 14 17:53:25 volumio volumio[985]: info: CorePlayQueue::getTrack 0 Apr 14 17:53:25 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 17:53:30 volumio kernel: hwmon hwmon1: Voltage normalised Apr 14 17:53:35 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 14 17:53:41 volumio kernel: hwmon hwmon1: Voltage normalised Apr 14 17:53:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 17:53:43 volumio volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 17:53:45 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 14 17:53:49 volumio kernel: hwmon hwmon1: Voltage normalised Apr 14 17:53:50 volumio volumio[985]: info: Starting Uninstall of plugin system_controller - ampswitch Apr 14 17:53:50 volumio volumio[985]: info: Uninstalling plugin ampswitch Apr 14 17:53:50 volumio volumio[985]: info: [ASDebug] Port: 24 Apr 14 17:53:50 volumio volumio[985]: info: [ASDebug] Inverted: false Apr 14 17:53:50 volumio volumio[985]: info: [ASDebug] Delay: 720 Apr 14 17:53:55 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 14 17:54:04 volumio kernel: hwmon hwmon1: Voltage normalised Apr 14 17:54:05 volumio volumio[985]: info: Starting Uninstall of plugin system_controller - ampswitch Apr 14 17:54:05 volumio volumio[985]: info: Uninstalling plugin ampswitch Apr 14 17:54:05 volumio volumio[985]: info: [ASDebug] Port: 24 Apr 14 17:54:05 volumio volumio[985]: info: [ASDebug] Inverted: false Apr 14 17:54:05 volumio volumio[985]: info: [ASDebug] Delay: 720 Apr 14 17:54:05 volumio volumio[985]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 17:54:05 volumio volumio[985]: Error: EBADF: bad file descriptor, close Apr 14 17:54:05 volumio volumio[985]: at Object.closeSync (fs.js:439:3) Apr 14 17:54:05 volumio volumio[985]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Apr 14 17:54:05 volumio volumio[985]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Apr 14 17:54:05 volumio volumio[985]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Apr 14 17:54:05 volumio volumio[985]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Apr 14 17:54:05 volumio volumio[985]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Apr 14 17:54:05 volumio volumio[985]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 14 17:54:05 volumio volumio[985]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Apr 14 17:54:05 volumio volumio[985]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Apr 14 17:54:05 volumio volumio[985]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Apr 14 17:54:05 volumio volumio[985]: at Socket.emit (events.js:315:20) Apr 14 17:54:05 volumio volumio[985]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 14 17:54:05 volumio volumio[985]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Apr 14 17:54:05 volumio volumio[985]: errno: -9, Apr 14 17:54:05 volumio volumio[985]: syscall: 'close', Apr 14 17:54:05 volumio volumio[985]: code: 'EBADF' Apr 14 17:54:05 volumio volumio[985]: } Apr 14 17:54:05 volumio volumio[985]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 17:54:06 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 14 17:54:06 volumio sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-14 17:53 Apr 14 17:54:06 volumio sudo[2072]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"