-- Logs begin at Fri 2024-11-15 03:20:42 MSK, end at Wed 2025-01-15 14:04:29 MSK. -- Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 14:03:04 volumio volumio[1285]: info: Retrieving Cloud Streaming UI Jan 15 14:03:04 volumio volumio[1285]: info: Getting Tidal Cloud Configuration Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 14:03:04 volumio volumio[1285]: info: Getting Qobuz Cloud Configuration Jan 15 14:03:04 volumio volumio[1285]: info: Asking plugin for UI Config Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 14:03:04 volumio volumio[1285]: info: Getting Spotify Cloud Configuration Jan 15 14:03:04 volumio volumio[1285]: info: Asking plugin for UI Config Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 14:03:04 volumio volumio[1285]: info: Saving Spotify Acccount Jan 15 14:03:04 volumio volumio[1285]: info: Got it Jan 15 14:03:04 volumio volumio[1285]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 15 14:03:04 volumio volumio[1285]: info: Got Tidal Cloud Configuration Jan 15 14:03:04 volumio volumio[1285]: info: Got it Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::volumioGetBrowseSources Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::volumioGetBrowseSources Jan 15 14:03:04 volumio volumio[1285]: info: CoreCommandRouter::volumioGetBrowseSources Jan 15 14:03:05 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:05 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 15 14:03:09 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:13 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:15 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Jan 15 14:03:15 volumio volumio[1285]: info: Share NAS successfully unmounted Jan 15 14:03:15 volumio sudo[11732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.113/\\NAS\Music\Music\Albums /mnt/NAS/NAS Jan 15 14:03:15 volumio sudo[11732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 15 14:03:15 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -6 Jan 15 14:03:15 volumio sudo[11732]: pam_unix(sudo:session): session closed for user root Jan 15 14:03:15 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -6 Jan 15 14:03:15 volumio volumio[1285]: info: An error occurred mounting the new share. Rolling back configuration Jan 15 14:03:16 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 15 14:03:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:21 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:25 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand update Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 15 14:03:26 volumio volumio[1285]: info: Jan 15 14:03:26 volumio volumio[1285]: ---------------------------- MPD announces state update: update Jan 15 14:03:26 volumio volumio[1285]: info: sendMpdCommand update took 4 milliseconds Jan 15 14:03:26 volumio volumio[1285]: info: ControllerMpd::getState Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 15 14:03:26 volumio volumio[1285]: info: Jan 15 14:03:26 volumio volumio[1285]: ---------------------------- MPD announces state update: update Jan 15 14:03:26 volumio volumio[1285]: info: ControllerMpd::getState Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 15 14:03:26 volumio volumio[1285]: info: sendMpdCommand status took 6 milliseconds Jan 15 14:03:26 volumio volumio[1285]: info: sendMpdCommand status took 4 milliseconds Jan 15 14:03:26 volumio volumio[1285]: info: sendMpdCommand status took 3 milliseconds Jan 15 14:03:26 volumio volumio[1285]: info: sendMpdCommand status took 2 milliseconds Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 15 14:03:26 volumio volumio[1285]: info: Command Router : Notfying DB Updatetrue Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 15 14:03:26 volumio volumio[1285]: info: Command Router : Notfying DB Updatetrue Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::Close All Modals sent Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 15 14:03:26 volumio volumio[1285]: info: ControllerMpd::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 15 14:03:26 volumio volumio[1285]: verbose: CURRENT POSITION 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::syncState stateService stop Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus stop Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: No code Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: ControllerMpd::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 15 14:03:26 volumio volumio[1285]: verbose: CURRENT POSITION 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::syncState stateService stop Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus stop Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: No code Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: ------------------------------ 63ms Jan 15 14:03:26 volumio volumio[1285]: info: ------------------------------ 61ms Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 15 14:03:26 volumio volumio[1285]: info: Jan 15 14:03:26 volumio volumio[1285]: ---------------------------- MPD announces state update: update Jan 15 14:03:26 volumio volumio[1285]: info: ControllerMpd::getState Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 15 14:03:26 volumio volumio[1285]: info: Jan 15 14:03:26 volumio volumio[1285]: ---------------------------- MPD announces state update: update Jan 15 14:03:26 volumio volumio[1285]: info: ControllerMpd::getState Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::sendMpdCommand status Jan 15 14:03:26 volumio volumio[1285]: info: sendMpdCommand status took 19 milliseconds Jan 15 14:03:26 volumio volumio[1285]: info: sendMpdCommand status took 18 milliseconds Jan 15 14:03:26 volumio volumio[1285]: info: sendMpdCommand status took 18 milliseconds Jan 15 14:03:26 volumio volumio[1285]: info: sendMpdCommand status took 18 milliseconds Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 15 14:03:26 volumio volumio[1285]: info: Command Router : Notfying DB Updatefalse Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::Close All Modals sent Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 15 14:03:26 volumio volumio[1285]: info: Command Router : Notfying DB Updatefalse Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::Close All Modals sent Jan 15 14:03:26 volumio volumio[1285]: verbose: ControllerMpd::parseState Jan 15 14:03:26 volumio volumio[1285]: info: ControllerMpd::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 15 14:03:26 volumio volumio[1285]: verbose: CURRENT POSITION 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::syncState stateService stop Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus stop Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: No code Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: ControllerMpd::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::servicePushState Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 15 14:03:26 volumio volumio[1285]: verbose: CURRENT POSITION 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::syncState stateService stop Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::syncState currentStatus stop Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: No code Jan 15 14:03:26 volumio volumio[1285]: info: CoreStateMachine::pushState Jan 15 14:03:26 volumio volumio[1285]: info: CorePlayQueue::getTrack 0 Jan 15 14:03:26 volumio volumio[1285]: info: CoreCommandRouter::volumioPushState Jan 15 14:03:26 volumio volumio[1285]: info: ------------------------------ 75ms Jan 15 14:03:26 volumio volumio[1285]: info: ------------------------------ 74ms Jan 15 14:03:29 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:33 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:37 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:41 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:45 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:49 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:53 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:03:57 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:04:01 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:04:05 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:04:09 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:04:13 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:04:17 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:04:21 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:04:25 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:04:26 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 15 14:04:26 volumio nmbd[754]: [2025/01/15 14:04:26.646471, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 15 14:04:26 volumio nmbd[754]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.151 for name WORKGROUP<1d>. Jan 15 14:04:26 volumio nmbd[754]: This response was from IP 192.168.1.206, reporting an IP address of 192.168.1.206. Jan 15 14:04:26 volumio nmbd[754]: [2025/01/15 14:04:26.646947, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 15 14:04:26 volumio nmbd[754]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.63 for name WORKGROUP<1d>. Jan 15 14:04:26 volumio nmbd[754]: This response was from IP 192.168.1.206, reporting an IP address of 192.168.1.206. Jan 15 14:04:27 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 15 14:04:29 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 15 14:04:29 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 15 14:04:29 volumio volumio[1285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 15 14:04:29 volumio volumio[1285]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 14:04:29 volumio volumio[1285]: Error: getaddrinfo -3007 Jan 15 14:04:29 volumio volumio[1285]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jan 15 14:04:29 volumio volumio[1285]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jan 15 14:04:29 volumio volumio[1285]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jan 15 14:04:29 volumio volumio[1285]: code: -3007, Jan 15 14:04:29 volumio volumio[1285]: errno: -3007, Jan 15 14:04:29 volumio volumio[1285]: syscall: 'getaddrinfo' Jan 15 14:04:29 volumio volumio[1285]: } Jan 15 14:04:29 volumio volumio[1285]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 15 14:04:29 volumio sudo[11942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-15 14:03 Jan 15 14:04:29 volumio sudo[11942]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:11:38 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="932fc3546f7cc8faa2b4d349036752dd"