-- 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"