-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Sun 2025-06-22 17:58:56 MSK. -- Jun 22 17:57:41 vinyl volumio[1014]: verbose: New Socket.io Connection to 192.168.0.52 from 192.168.0.118 UA: Mozilla/5.0 (iPad; CPU OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::volumioGetVisibleSources Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 22 17:57:41 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 22 17:57:41 vinyl volumio[1014]: info: Received Get System Info Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 17:57:41 vinyl volumio[1014]: info: Discovery: Getting this device information Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 22 17:57:41 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 22 17:57:41 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:57:41 vinyl volumio[1014]: info: Listing playlists Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::volumioGetQueue Jun 22 17:57:41 vinyl volumio[1014]: info: CoreStateMachine::getQueue Jun 22 17:57:41 vinyl volumio[1014]: info: CorePlayQueue::getQueue Jun 22 17:57:41 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 22 17:57:43 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:57:47 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:57:51 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:57:55 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:57:59 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:03 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:07 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:08 vinyl volumio[1014]: info: CALLMETHOD: miscellanea my_music updateMusicLibrarySupersearchSources [object Object] Jun 22 17:58:08 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibrarySupersearchSources Jun 22 17:58:08 vinyl volumio[1014]: info: CoreCommandRouter::volumioGetBrowseSources Jun 22 17:58:08 vinyl volumio[1014]: info: CoreCommandRouter::volumioGetBrowseSources Jun 22 17:58:08 vinyl volumio[1014]: info: CoreCommandRouter::updateGlobalSearchableBrowseSources Jun 22 17:58:11 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:15 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:19 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand rescan Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: info: Jun 22 17:58:22 vinyl volumio[1014]: ---------------------------- MPD announces state update: update Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand rescan took 3 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::getState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: info: Jun 22 17:58:22 vinyl volumio[1014]: ---------------------------- MPD announces state update: update Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::getState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: info: Jun 22 17:58:22 vinyl volumio[1014]: ---------------------------- MPD announces state update: update Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::getState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: info: Jun 22 17:58:22 vinyl volumio[1014]: ---------------------------- MPD announces state update: update Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::getState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 7 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 5 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 6 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 5 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 4 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 3 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: info: Command Router : Notfying DB Updatefalse Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::Close All Modals sent Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: info: Command Router : Notfying DB Updatefalse Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::Close All Modals sent Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: info: Command Router : Notfying DB Updatefalse Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::Close All Modals sent Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::servicePushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreStateMachine::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::servicePushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreStateMachine::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::servicePushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreStateMachine::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jun 22 17:58:22 vinyl volumio[1014]: info: ------------------------------ 151ms Jun 22 17:58:22 vinyl volumio[1014]: info: ------------------------------ 149ms Jun 22 17:58:22 vinyl volumio[1014]: info: ------------------------------ 148ms Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: info: Jun 22 17:58:22 vinyl volumio[1014]: ---------------------------- MPD announces state update: update Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::getState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: info: Jun 22 17:58:22 vinyl volumio[1014]: ---------------------------- MPD announces state update: update Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::getState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 150 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 149 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 3 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 2 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 3 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: info: sendMpdCommand status took 2 milliseconds Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: info: Command Router : Notfying DB Updatefalse Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::Close All Modals sent Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: info: Command Router : Notfying DB Updatefalse Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::Close All Modals sent Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: info: Command Router : Notfying DB Updatefalse Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::Close All Modals sent Jun 22 17:58:22 vinyl volumio[1014]: verbose: ControllerMpd::parseState Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::servicePushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreStateMachine::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::servicePushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreStateMachine::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jun 22 17:58:22 vinyl volumio[1014]: info: ControllerMpd::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::servicePushState Jun 22 17:58:22 vinyl volumio[1014]: info: CoreStateMachine::pushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 22 17:58:22 vinyl volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 22 17:58:22 vinyl volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Jun 22 17:58:22 vinyl volumio[1014]: info: ------------------------------ 228ms Jun 22 17:58:22 vinyl volumio[1014]: info: ------------------------------ 81ms Jun 22 17:58:22 vinyl volumio[1014]: info: ------------------------------ 81ms Jun 22 17:58:22 vinyl volumio[1014]: info: [ASDebug] CurState: stop PrevState: stop Jun 22 17:58:22 vinyl volumio[1014]: info: [ASDebug] CurState: stop PrevState: stop Jun 22 17:58:22 vinyl volumio[1014]: info: [ASDebug] CurState: stop PrevState: stop Jun 22 17:58:22 vinyl volumio[1014]: info: [ASDebug] CurState: stop PrevState: stop Jun 22 17:58:22 vinyl volumio[1014]: info: [ASDebug] CurState: stop PrevState: stop Jun 22 17:58:22 vinyl volumio[1014]: info: [ASDebug] CurState: stop PrevState: stop Jun 22 17:58:22 vinyl volumio[1014]: SPOTIFY: RECEIVED VOLUMIO VOLUME 22 Jun 22 17:58:22 vinyl volumio[1014]: SPOTIFY: RECEIVED VOLUMIO VOLUME 22 Jun 22 17:58:22 vinyl volumio[1014]: SPOTIFY: RECEIVED VOLUMIO VOLUME 22 Jun 22 17:58:22 vinyl volumio[1014]: SPOTIFY: RECEIVED VOLUMIO VOLUME 22 Jun 22 17:58:22 vinyl volumio[1014]: SPOTIFY: RECEIVED VOLUMIO VOLUME 22 Jun 22 17:58:22 vinyl volumio[1014]: SPOTIFY: RECEIVED VOLUMIO VOLUME 22 Jun 22 17:58:23 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:27 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:31 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:35 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:39 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Jun 22 17:58:39 vinyl volumio[1014]: info: Share NAS successfully unmounted Jun 22 17:58:39 vinyl sudo[23875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.4:\\Multimedia\\Music /mnt/NAS/NAS Jun 22 17:58:39 vinyl sudo[23875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 22 17:58:39 vinyl sudo[23875]: pam_unix(sudo:session): session closed for user root Jun 22 17:58:39 vinyl volumio[1014]: info: An error occurred mounting the new share. Rolling back configuration Jun 22 17:58:39 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:40 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 22 17:58:43 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:47 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:50 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 22 17:58:50 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 22 17:58:52 vinyl volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 22 17:58:52 vinyl volumio[1014]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L Keenetic-9530.local. Jun 22 17:58:52 vinyl volumio[1014]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L NASik.local. Jun 22 17:58:53 vinyl volumio[1014]: info: Error browsing device NASik for shares: Command failed: smbclient --no-pass --debuglevel=0 -L NASik.local. Jun 22 17:58:53 vinyl volumio[1014]: Unable to initialize messaging context Jun 22 17:58:55 vinyl volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 17:58:55 vinyl volumio[1014]: Error: dns service error: unknown Jun 22 17:58:55 vinyl volumio[1014]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jun 22 17:58:55 vinyl volumio[1014]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 22 17:58:55 vinyl volumio[1014]: errorCode: -65537 Jun 22 17:58:55 vinyl volumio[1014]: } Jun 22 17:58:55 vinyl volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 17:58:56 vinyl sudo[23940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-22 17:57 Jun 22 17:58:56 vinyl sudo[23940]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"