-- Logs begin at Wed 2024-07-24 20:06:23 JST, end at Wed 2024-07-24 20:13:30 JST. -- Jul 24 20:12:00 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 24 20:12:02 fafapi volumio[1967]: info: MYVOLUMIO: Adding device Jul 24 20:12:02 fafapi volumio[1967]: info: MYVOLUMIO: Evaluating Server Jul 24 20:12:02 fafapi volumio[1967]: info: Setting Geolocation for MyVolumio to as1 Jul 24 20:12:02 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 20:12:02 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 20:12:02 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 20:12:03 fafapi volumio[1967]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 24 20:12:03 fafapi volumio[1967]: info: Updating MyVolumio device info Jul 24 20:12:03 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 20:12:03 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 20:12:03 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 20:12:03 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:04 fafapi volumio[1967]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 24 20:12:07 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 24 20:12:07 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:08 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 24 20:12:09 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 24 20:12:09 fafapi volumio[1967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 24 20:12:09 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetState Jul 24 20:12:09 fafapi volumio[1967]: info: CorePlayQueue::getTrack 0 Jul 24 20:12:11 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:12 fafapi nmbd[933]: [2024/07/24 20:12:12.659868, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 24 20:12:12 fafapi nmbd[933]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.179 for name WORKGROUP<1d>. Jul 24 20:12:12 fafapi nmbd[933]: This response was from IP 192.168.3.45, reporting an IP address of 192.168.3.45. Jul 24 20:12:12 fafapi nmbd[933]: [2024/07/24 20:12:12.660065, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 24 20:12:12 fafapi nmbd[933]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.178 for name WORKGROUP<1d>. Jul 24 20:12:12 fafapi nmbd[933]: This response was from IP 192.168.3.45, reporting an IP address of 192.168.3.45. Jul 24 20:12:14 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 20:12:14 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 20:12:14 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 24 20:12:14 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 24 20:12:14 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 24 20:12:14 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 20:12:14 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 24 20:12:15 fafapi volumio[1967]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 24 20:12:15 fafapi volumio[1967]: info: Completed starting MyVolumio Plugin Jul 24 20:12:15 fafapi volumio[1967]: info: BOOT COMPLETED Jul 24 20:12:15 fafapi volumio[1967]: [Metrics] CommandRouter: 60s 118.20ms Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::volumiosetStartupVolume Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::Close All Modals sent Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::Close All Modals sent Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:15 fafapi volumio-remote-updater[829]: No test mode Jul 24 20:12:15 fafapi volumio-remote-updater[829]: No alpha test mode Jul 24 20:12:15 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 24 20:12:16 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 24 20:12:16 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 24 20:12:16 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 24 20:12:16 fafapi volumio[1967]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 24 20:12:16 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 24 20:12:19 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetState Jul 24 20:12:19 fafapi volumio[1967]: info: CorePlayQueue::getTrack 0 Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:22 fafapi volumio[1967]: info: Retrieving Cloud Streaming UI Jul 24 20:12:22 fafapi volumio[1967]: info: Getting Tidal Cloud Configuration Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:22 fafapi volumio[1967]: info: Getting Qobuz Cloud Configuration Jul 24 20:12:22 fafapi volumio[1967]: info: Asking plugin for UI Config Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:22 fafapi volumio[1967]: info: Getting Spotify Cloud Configuration Jul 24 20:12:22 fafapi volumio[1967]: info: Asking plugin for UI Config Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:22 fafapi volumio[1967]: info: Saving Spotify Acccount Jul 24 20:12:22 fafapi volumio[1967]: info: Got Tidal Cloud Configuration Jul 24 20:12:22 fafapi volumio[1967]: info: Got it Jul 24 20:12:22 fafapi volumio[1967]: info: Got it Jul 24 20:12:22 fafapi volumio[1967]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:22 fafapi volumio[1967]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:22 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 24 20:12:26 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:26 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 24 20:12:27 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 24 20:12:30 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:30 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 24 20:12:30 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 24 20:12:32 fafapi volumio[1967]: info: Error browsing device NASN for shares : Error: Command failed: echo volumio | smbclient -L NASN.local. Jul 24 20:12:32 fafapi volumio[1967]: Unable to initialize messaging context Jul 24 20:12:32 fafapi volumio[1967]: info: Error browsing device HAP-S1 for shares : Error: Command failed: echo volumio | smbclient -L HAP-S1-D8D43CF956A0.local. Jul 24 20:12:32 fafapi volumio[1967]: Unable to initialize messaging context Jul 24 20:12:32 fafapi volumio[1967]: do_connect: Connection to HAP-S1-D8D43CF956A0.local. failed (Error NT_STATUS_CONNECTION_REFUSED) Jul 24 20:12:32 fafapi volumio[1967]: info: Error browsing device TECHNIQUE for shares : Error: Command failed: echo volumio | smbclient -L TECHNIQUE.local. Jul 24 20:12:32 fafapi volumio[1967]: Unable to initialize messaging context Jul 24 20:12:34 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:34 fafapi sudo[2201]: pam_unix(sudo:session): session closed for user root Jul 24 20:12:34 fafapi volumio[1967]: info: Error mounting NASS2 on IP 192.168.3.181 : exec error Error: Command failed: /usr/bin/sudo /bin/mount -t nfs -o ro,soft,noauto '192.168.3.181:/music' '/mnt/NAS/NASS2' Jul 24 20:12:34 fafapi volumio[1967]: mount.nfs: mount to NFS server '192.168.3.181:/music' failed: RPC Error: Unable to receive Jul 24 20:12:35 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 24 20:12:38 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:42 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:46 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetState Jul 24 20:12:46 fafapi volumio[1967]: info: CorePlayQueue::getTrack 0 Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:48 fafapi volumio[1967]: info: Retrieving Cloud Streaming UI Jul 24 20:12:48 fafapi volumio[1967]: info: Getting Tidal Cloud Configuration Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:48 fafapi volumio[1967]: info: Getting Qobuz Cloud Configuration Jul 24 20:12:48 fafapi volumio[1967]: info: Asking plugin for UI Config Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:48 fafapi volumio[1967]: info: Getting Spotify Cloud Configuration Jul 24 20:12:48 fafapi volumio[1967]: info: Asking plugin for UI Config Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:48 fafapi volumio[1967]: info: Saving Spotify Acccount Jul 24 20:12:48 fafapi volumio[1967]: info: Got Tidal Cloud Configuration Jul 24 20:12:48 fafapi volumio[1967]: info: Got it Jul 24 20:12:48 fafapi volumio[1967]: info: Got it Jul 24 20:12:48 fafapi volumio[1967]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 24 20:12:48 fafapi volumio[1967]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::volumioGetBrowseSources Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:48 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 24 20:12:52 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:12:56 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:13:01 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:13:05 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:13:09 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:13:13 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:13:17 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:13:21 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:13:25 fafapi sudo[2006]: pam_unix(sudo:session): session closed for user root Jul 24 20:13:25 fafapi volumio[1967]: info: Cannot mount NAS NASS at system boot, trial number 1 ,retrying in 5 seconds Jul 24 20:13:25 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:13:29 fafapi volumio[1967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 24 20:13:30 fafapi volumio[1967]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 20:13:30 fafapi volumio[1967]: TypeError: Cannot read property 'replace' of undefined Jul 24 20:13:30 fafapi volumio[1967]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jul 24 20:13:30 fafapi volumio[1967]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jul 24 20:13:30 fafapi volumio[1967]: at listOnTimeout (internal/timers.js:554:17) Jul 24 20:13:30 fafapi volumio[1967]: at processTimers (internal/timers.js:497:7) Jul 24 20:13:30 fafapi volumio[1967]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 24 20:13:30 fafapi sudo[2432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-24 20:12 Jul 24 20:13:30 fafapi sudo[2432]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"