-- Logs begin at Thu 2024-10-24 20:00:10 BST, end at Thu 2024-10-24 20:04:19 BST. -- Oct 24 20:03:01 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:04 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Oct 24 20:03:04 v-lounge volumio[797]: info: Share DataCave successfully unmounted Oct 24 20:03:04 v-lounge sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.10.130:/volume2/Music /mnt/NAS/DataCave Oct 24 20:03:04 v-lounge sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 20:03:05 v-lounge sudo[1691]: pam_unix(sudo:session): session closed for user root Oct 24 20:03:05 v-lounge volumio[797]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:03:05 v-lounge volumio[797]: info: Oct 24 20:03:05 v-lounge volumio[797]: ---------------------------- MPD announces state update: update Oct 24 20:03:05 v-lounge volumio[797]: info: ControllerMpd::getState Oct 24 20:03:05 v-lounge volumio[797]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:03:05 v-lounge volumio[797]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:03:05 v-lounge volumio[797]: info: Oct 24 20:03:05 v-lounge volumio[797]: ---------------------------- MPD announces state update: update Oct 24 20:03:05 v-lounge volumio[797]: info: ControllerMpd::getState Oct 24 20:03:05 v-lounge volumio[797]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:03:05 v-lounge volumio[797]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:03:05 v-lounge volumio[797]: info: Oct 24 20:03:05 v-lounge volumio[797]: ---------------------------- MPD announces state update: update Oct 24 20:03:05 v-lounge volumio[797]: info: ControllerMpd::getState Oct 24 20:03:05 v-lounge volumio[797]: verbose: ControllerMpd::sendMpdCommand status Oct 24 20:03:05 v-lounge volumio[797]: info: [1729796585048] Database update started Oct 24 20:03:05 v-lounge nfsidmap[1704]: nss_getpwnam: name 'chris' not found in domain 'localdomain' Oct 24 20:03:05 v-lounge volumio[797]: info: sendMpdCommand status took 17 milliseconds Oct 24 20:03:05 v-lounge volumio[797]: info: sendMpdCommand status took 16 milliseconds Oct 24 20:03:05 v-lounge volumio[797]: info: sendMpdCommand status took 16 milliseconds Oct 24 20:03:05 v-lounge volumio[797]: info: sendMpdCommand status took 15 milliseconds Oct 24 20:03:05 v-lounge volumio[797]: info: sendMpdCommand status took 15 milliseconds Oct 24 20:03:05 v-lounge volumio[797]: info: sendMpdCommand status took 14 milliseconds Oct 24 20:03:05 v-lounge volumio[797]: verbose: ControllerMpd::parseState Oct 24 20:03:11 v-lounge volumio[797]: info: Command Router : Notfying DB Updatetrue Oct 24 20:03:11 v-lounge volumio[797]: info: CoreCommandRouter::Close All Modals sent Oct 24 20:03:11 v-lounge volumio[797]: verbose: ControllerMpd::parseState Oct 24 20:03:11 v-lounge volumio[797]: verbose: ControllerMpd::parseState Oct 24 20:03:15 v-lounge volumio[797]: info: Command Router : Notfying DB Updatetrue Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::Close All Modals sent Oct 24 20:03:15 v-lounge volumio[797]: verbose: ControllerMpd::parseState Oct 24 20:03:15 v-lounge volumio[797]: verbose: ControllerMpd::parseState Oct 24 20:03:15 v-lounge volumio[797]: info: Command Router : Notfying DB Updatetrue Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::Close All Modals sent Oct 24 20:03:15 v-lounge volumio[797]: verbose: ControllerMpd::parseState Oct 24 20:03:15 v-lounge volumio[797]: info: ControllerMpd::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::servicePushState Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::volumioPushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: 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} Oct 24 20:03:15 v-lounge volumio[797]: verbose: CURRENT POSITION 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::syncState stateService stop Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::syncState currentStatus stop Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::volumioPushState Oct 24 20:03:15 v-lounge volumio[797]: info: No code Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::volumioPushState Oct 24 20:03:15 v-lounge volumio[797]: info: ControllerMpd::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::servicePushState Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::volumioPushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: 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} Oct 24 20:03:15 v-lounge volumio[797]: verbose: CURRENT POSITION 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::syncState stateService stop Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::syncState currentStatus stop Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::volumioPushState Oct 24 20:03:15 v-lounge volumio[797]: info: No code Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::volumioPushState Oct 24 20:03:15 v-lounge volumio[797]: info: ControllerMpd::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::servicePushState Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::volumioPushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: 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} Oct 24 20:03:15 v-lounge volumio[797]: verbose: CURRENT POSITION 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::syncState stateService stop Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::syncState currentStatus stop Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::volumioPushState Oct 24 20:03:15 v-lounge volumio[797]: info: No code Oct 24 20:03:15 v-lounge volumio[797]: info: CoreStateMachine::pushState Oct 24 20:03:15 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::volumioPushState Oct 24 20:03:15 v-lounge volumio[797]: info: ------------------------------ 10309ms Oct 24 20:03:15 v-lounge volumio[797]: info: ------------------------------ 10308ms Oct 24 20:03:15 v-lounge volumio[797]: info: ------------------------------ 10307ms Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:15 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 24 20:03:16 v-lounge volumio[797]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 24 20:03:16 v-lounge volumio[797]: info: CoreCommandRouter::volumioGetState Oct 24 20:03:16 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:03:17 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:21 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:25 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:29 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:33 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:37 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:41 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:45 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:03:49 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:04:08 v-lounge volumio[797]: info: Discovery: Getting this device information Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::volumioGetState Oct 24 20:04:08 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:04:08 v-lounge volumio[797]: verbose: New Socket.io Connection to 192.168.10.82:3000 from 192.168.10.11 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 24 20:04:08 v-lounge volumio[797]: verbose: New Socket.io Connection to 192.168.10.82 from 192.168.10.11 UA: Mozilla/5.0 (Linux; Android 14; CPH2581 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::volumioGetState Oct 24 20:04:08 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 20:04:08 v-lounge volumio[797]: info: Received Get System Info Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:04:08 v-lounge volumio[797]: info: Discovery: Getting this device information Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::volumioGetState Oct 24 20:04:08 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:04:08 v-lounge volumio[797]: info: CoreCommandRouter::volumioGetState Oct 24 20:04:08 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:04:08 v-lounge volumio[797]: info: Listing playlists Oct 24 20:04:09 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:04:09 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:04:09 v-lounge volumio[797]: info: Discovery: Getting this device information Oct 24 20:04:09 v-lounge volumio[797]: info: CoreCommandRouter::volumioGetState Oct 24 20:04:09 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:04:09 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:04:09 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 20:04:09 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 20:04:09 v-lounge volumio[797]: info: Discovery: Getting this device information Oct 24 20:04:09 v-lounge volumio[797]: info: CoreCommandRouter::volumioGetState Oct 24 20:04:09 v-lounge volumio[797]: info: CorePlayQueue::getTrack 0 Oct 24 20:04:09 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 20:04:09 v-lounge volumio[797]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 24 20:04:13 v-lounge sudo[898]: pam_unix(sudo:session): session closed for user root Oct 24 20:04:13 v-lounge volumio[797]: info: Cannot mount NAS DataCave at system boot, trial number 1 ,retrying in 5 seconds Oct 24 20:04:18 v-lounge volumio[797]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 20:04:19 v-lounge volumio[797]: TypeError: Cannot read property 'replace' of undefined Oct 24 20:04:19 v-lounge volumio[797]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Oct 24 20:04:19 v-lounge volumio[797]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Oct 24 20:04:19 v-lounge volumio[797]: at listOnTimeout (internal/timers.js:554:17) Oct 24 20:04:19 v-lounge volumio[797]: at processTimers (internal/timers.js:497:7) Oct 24 20:04:19 v-lounge volumio[797]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 20:04:19 v-lounge sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 20:03 Oct 24 20:04:19 v-lounge sudo[1773]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"