-- Logs begin at Mon 2024-11-25 23:09:04 CST, end at Mon 2024-11-25 23:13:25 CST. -- Nov 25 23:12:07 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:12:07 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:12:13 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:12:13 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:12:16 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:12:16 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 25 23:12:19 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 25 23:12:23 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:12:23 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:12:25 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:12:25 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 23:12:26 volumio volumio[1277]: info: Retrieving Cloud Streaming UI Nov 25 23:12:26 volumio volumio[1277]: info: Getting Tidal Cloud Configuration Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 23:12:26 volumio volumio[1277]: info: Getting Qobuz Cloud Configuration Nov 25 23:12:26 volumio volumio[1277]: info: Asking plugin for UI Config Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 23:12:26 volumio volumio[1277]: info: Getting Spotify Cloud Configuration Nov 25 23:12:26 volumio volumio[1277]: info: Asking plugin for UI Config Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 23:12:26 volumio volumio[1277]: info: Saving Spotify Acccount Nov 25 23:12:26 volumio volumio[1277]: info: Got Tidal Cloud Configuration Nov 25 23:12:26 volumio volumio[1277]: info: Got it Nov 25 23:12:26 volumio volumio[1277]: info: Got it Nov 25 23:12:26 volumio volumio[1277]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 25 23:12:26 volumio volumio[1277]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::volumioGetBrowseSources Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::volumioGetBrowseSources Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::volumioGetBrowseSources Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:12:26 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:12:26 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:26 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:12:26 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:26 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 25 23:12:30 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:12:30 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:12:30 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:30 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:12:30 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:31 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 25 23:12:31 volumio volumio[1277]: error: Cannot Delete Folder. Error: Error: Command failed: rm -rf /mnt/NAS/music Nov 25 23:12:31 volumio volumio[1277]: rm: cannot remove '/mnt/NAS/music': Permission denied Nov 25 23:12:32 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 25 23:12:34 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 25 23:12:34 volumio volumio[1277]: error: Cannot Delete Folder. Error: Error: Command failed: rm -rf /mnt/NAS/music Nov 25 23:12:34 volumio volumio[1277]: rm: cannot remove '/mnt/NAS/music': Permission denied Nov 25 23:12:34 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:12:34 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:12:34 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:34 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:12:34 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:34 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:12:34 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:12:35 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 25 23:12:37 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 25 23:12:37 volumio volumio[1277]: error: Cannot Delete Folder. Error: Error: Command failed: rm -rf /mnt/NAS/music Nov 25 23:12:37 volumio volumio[1277]: rm: cannot remove '/mnt/NAS/music': Permission denied Nov 25 23:12:38 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:12:38 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:12:38 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:38 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:12:38 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:38 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 25 23:12:42 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:12:42 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:12:42 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:42 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:12:42 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:43 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:12:43 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:12:45 volumio volumio[1277]: info: Enabling MyMusic plugin metavolumio Nov 25 23:12:45 volumio volumio[1277]: info: [MyVolumio PluginManager] Enabling and starting plugin miscellanea metavolumio Nov 25 23:12:45 volumio volumio[1277]: info: [MyVolumio PluginManager] Plugin miscellanea metavolumio not in user plan, not enabling Nov 25 23:12:45 volumio volumio[1277]: info: Error: Plugin miscellanea metavolumio not in user plan, not enabling Nov 25 23:12:45 volumio volumio[1277]: error: Could not Enable MyMusic Plugin: Error Nov 25 23:12:46 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:12:46 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:12:46 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:46 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:12:46 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:50 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:12:50 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:12:50 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:50 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:12:50 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:52 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:12:52 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:12:54 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:12:54 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:12:54 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:54 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:12:54 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:58 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:12:58 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:12:58 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:12:58 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:12:58 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:01 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:13:01 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:13:02 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:13:02 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:13:02 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:02 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:13:02 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:06 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:13:06 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:13:06 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:06 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:13:06 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:10 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:13:10 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:13:10 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:10 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:13:10 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:10 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:13:10 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:13:14 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:13:14 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:13:14 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:14 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:13:14 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:18 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:13:18 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:13:18 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:18 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:13:18 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:19 volumio volumio[1277]: info: CoreCommandRouter::volumioGetState Nov 25 23:13:19 volumio volumio[1277]: info: CorePlayQueue::getTrack 2 Nov 25 23:13:22 volumio volumio[1277]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 25 23:13:22 volumio volumio[1277]: error: MPD error: The expression evaluated to a falsy value: Nov 25 23:13:22 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:22 volumio volumio[1277]: error: The expression evaluated to a falsy value: Nov 25 23:13:22 volumio volumio[1277]: assert.ok(self.idling) Nov 25 23:13:24 volumio volumio[1277]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 23:13:24 volumio volumio[1277]: Error: connect ETIMEDOUT 192.133.77.145:443 Nov 25 23:13:24 volumio volumio[1277]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 25 23:13:24 volumio volumio[1277]: errno: -110, Nov 25 23:13:24 volumio volumio[1277]: code: 'ETIMEDOUT', Nov 25 23:13:24 volumio volumio[1277]: syscall: 'connect', Nov 25 23:13:24 volumio volumio[1277]: address: '192.133.77.145', Nov 25 23:13:24 volumio volumio[1277]: port: 443 Nov 25 23:13:24 volumio volumio[1277]: } Nov 25 23:13:24 volumio volumio[1277]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 23:13:25 volumio sudo[2741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 23:12 Nov 25 23:13:25 volumio sudo[2741]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"