-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Sat 2024-10-26 15:57:01 UTC. -- Oct 26 15:56:01 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 15:56:02 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 15:56:02 volumio volumio[822]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 26 15:56:02 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 26 15:56:02 volumio volumio[822]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 26 15:56:02 volumio volumio[822]: info: Received Get System Version Oct 26 15:56:02 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 15:56:08 volumio volumio[822]: info: Retrieving Cloud Streaming UI Oct 26 15:56:08 volumio volumio[822]: info: Getting Tidal Cloud Configuration Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 15:56:08 volumio volumio[822]: info: Getting Qobuz Cloud Configuration Oct 26 15:56:08 volumio volumio[822]: info: Asking plugin for UI Config Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 15:56:08 volumio volumio[822]: info: Getting Spotify Cloud Configuration Oct 26 15:56:08 volumio volumio[822]: info: Asking plugin for UI Config Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 15:56:08 volumio volumio[822]: info: Saving Spotify Acccount Oct 26 15:56:08 volumio volumio[822]: info: Got Tidal Cloud Configuration Oct 26 15:56:08 volumio volumio[822]: info: Got it Oct 26 15:56:08 volumio volumio[822]: info: Got it Oct 26 15:56:08 volumio volumio[822]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 15:56:08 volumio volumio[822]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:08 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 15:56:11 volumio kernel: FS-Cache: Duplicate cookie detected Oct 26 15:56:11 volumio kernel: FS-Cache: O-cookie c=cde30a64 [p=a6c0d22b fl=222 nc=0 na=1] Oct 26 15:56:11 volumio kernel: FS-Cache: O-cookie d=1ba01950 n=44ce0ab9 Oct 26 15:56:11 volumio kernel: FS-Cache: O-key=[16] '040000000200000002000801c0a80013' Oct 26 15:56:11 volumio kernel: FS-Cache: N-cookie c=2455c9af [p=a6c0d22b fl=2 nc=0 na=1] Oct 26 15:56:11 volumio kernel: FS-Cache: N-cookie d=1ba01950 n=62225bf5 Oct 26 15:56:11 volumio kernel: FS-Cache: N-key=[16] '040000000200000002000801c0a80013' Oct 26 15:56:12 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:15 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 26 15:56:16 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 15:56:16 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:17 volumio kernel: Voltage normalised (0x00000000) Oct 26 15:56:19 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 26 15:56:20 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 15:56:20 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:21 volumio kernel: Under-voltage detected! (0x00050005) Oct 26 15:56:22 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 26 15:56:22 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 26 15:56:24 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Oct 26 15:56:24 volumio systemd[1]: Starting Preprocess NFS configuration... Oct 26 15:56:24 volumio systemd[1]: Reached target Host and Network Name Lookups. Oct 26 15:56:24 volumio systemd[1]: nfs-config.service: Succeeded. Oct 26 15:56:24 volumio systemd[1]: Started Preprocess NFS configuration. Oct 26 15:56:24 volumio systemd[1]: Starting Notify NFS peers of a restart... Oct 26 15:56:24 volumio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Oct 26 15:56:24 volumio sm-notify[1375]: Version 1.3.3 starting Oct 26 15:56:24 volumio systemd[1]: rpc-statd-notify.service: Succeeded. Oct 26 15:56:24 volumio systemd[1]: Started Notify NFS peers of a restart. Oct 26 15:56:24 volumio rpc.statd[1379]: Version 1.3.3 starting Oct 26 15:56:24 volumio rpc.statd[1379]: Flags: TI-RPC Oct 26 15:56:24 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:24 volumio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Oct 26 15:56:25 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:56:25 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:56:25 volumio volumio[822]: info: Discovery: Getting this device information Oct 26 15:56:25 volumio volumio[822]: info: CoreCommandRouter::volumioGetState Oct 26 15:56:25 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 26 15:56:25 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:56:28 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:32 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:36 volumio kernel: Voltage normalised (0x00000000) Oct 26 15:56:36 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:37 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Oct 26 15:56:37 volumio volumio[822]: info: Adding a new share Oct 26 15:56:37 volumio volumio[822]: info: No correspondence found in configuration for share 4t-data-new on IP 192.168.0.20 Oct 26 15:56:37 volumio sudo[1385]: 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.0.20/4t-data-new /mnt/NAS/4t-data-new Oct 26 15:56:37 volumio sudo[1385]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:56:37 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Oct 26 15:56:37 volumio kernel: Key type cifs.spnego registered Oct 26 15:56:37 volumio kernel: Key type cifs.idmap registered Oct 26 15:56:37 volumio kernel: CIFS: Attempting to mount //192.168.0.20/4t-data-new Oct 26 15:56:37 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 26 15:56:37 volumio sudo[1385]: pam_unix(sudo:session): session closed for user root Oct 26 15:56:37 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Oct 26 15:56:37 volumio volumio[822]: info: Permission denied for 4t-data-new on IP 192.168.0.20 Oct 26 15:56:38 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 15:56:40 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:44 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:48 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:52 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:55 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 15:56:55 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 15:56:55 volumio volumio[822]: info: Discovery: Getting this device information Oct 26 15:56:55 volumio volumio[822]: info: CoreCommandRouter::volumioGetState Oct 26 15:56:55 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 26 15:56:55 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 15:56:55 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Oct 26 15:56:55 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root Oct 26 15:56:55 volumio volumio[822]: info: Cannot mount NAS Synology at system boot, trial number 2 ,retrying in 5 seconds Oct 26 15:56:55 volumio volumio[822]: info: Cannot mount NAS Nas at system boot, trial number 2 ,retrying in 5 seconds Oct 26 15:56:56 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 15:56:58 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Oct 26 15:56:58 volumio volumio[822]: info: Share 4t-data-new successfully unmounted Oct 26 15:56:59 volumio sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sa,password=sa,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.20/4t-data-new /mnt/NAS/4t-data-new Oct 26 15:56:59 volumio sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 15:56:59 volumio kernel: CIFS: Attempting to mount //192.168.0.20/4t-data-new Oct 26 15:56:59 volumio sudo[1417]: pam_unix(sudo:session): session closed for user root Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:56:59 volumio volumio[822]: info: Oct 26 15:56:59 volumio volumio[822]: ---------------------------- MPD announces state update: update Oct 26 15:56:59 volumio volumio[822]: info: ControllerMpd::getState Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:56:59 volumio volumio[822]: info: Oct 26 15:56:59 volumio volumio[822]: ---------------------------- MPD announces state update: update Oct 26 15:56:59 volumio volumio[822]: info: ControllerMpd::getState Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:56:59 volumio volumio[822]: info: Oct 26 15:56:59 volumio volumio[822]: ---------------------------- MPD announces state update: update Oct 26 15:56:59 volumio volumio[822]: info: ControllerMpd::getState Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status Oct 26 15:56:59 volumio volumio[822]: info: sendMpdCommand status took 27 milliseconds Oct 26 15:56:59 volumio volumio[822]: info: sendMpdCommand status took 29 milliseconds Oct 26 15:56:59 volumio volumio[822]: info: sendMpdCommand status took 27 milliseconds Oct 26 15:56:59 volumio volumio[822]: info: sendMpdCommand status took 24 milliseconds Oct 26 15:56:59 volumio volumio[822]: info: sendMpdCommand status took 21 milliseconds Oct 26 15:56:59 volumio volumio[822]: info: sendMpdCommand status took 18 milliseconds Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::parseState Oct 26 15:56:59 volumio volumio[822]: info: Command Router : Notfying DB Updatetrue Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::parseState Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::parseState Oct 26 15:56:59 volumio volumio[822]: info: Command Router : Notfying DB Updatetrue Oct 26 15:56:59 volumio volumio[822]: info: CoreCommandRouter::Close All Modals sent Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::parseState Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::parseState Oct 26 15:56:59 volumio volumio[822]: info: Command Router : Notfying DB Updatetrue Oct 26 15:56:59 volumio volumio[822]: info: CoreCommandRouter::Close All Modals sent Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::parseState Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 15:56:59 volumio volumio[822]: info: [1729958219899] Database update started Oct 26 15:56:59 volumio volumio[822]: info: Oct 26 15:56:59 volumio volumio[822]: ---------------------------- MPD announces system playlist update Oct 26 15:56:59 volumio volumio[822]: info: Ignoring MPD Status Update Oct 26 15:56:59 volumio volumio[822]: info: sendMpdCommand playlistinfo took 165 milliseconds Oct 26 15:56:59 volumio volumio[822]: info: sendMpdCommand playlistinfo took 119 milliseconds Oct 26 15:56:59 volumio volumio[822]: info: sendMpdCommand playlistinfo took 74 milliseconds Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:56:59 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo Oct 26 15:56:59 volumio volumio[822]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 26 15:56:59 volumio volumio[822]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 26 15:56:59 volumio volumio[822]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 26 15:56:59 volumio volumio[822]: info: ------------------------------ 360ms Oct 26 15:56:59 volumio volumio[822]: info: ------------------------------ 352ms Oct 26 15:56:59 volumio volumio[822]: info: ------------------------------ 345ms Oct 26 15:56:59 volumio volumio[822]: info: Oct 26 15:56:59 volumio volumio[822]: ---------------------------- MPD announces system playlist update Oct 26 15:56:59 volumio volumio[822]: info: Ignoring MPD Status Update Oct 26 15:56:59 volumio volumio[822]: info: Oct 26 15:56:59 volumio volumio[822]: ---------------------------- MPD announces system playlist update Oct 26 15:56:59 volumio volumio[822]: info: Ignoring MPD Status Update Oct 26 15:56:59 volumio volumio[822]: error: updateQueue error: null Oct 26 15:56:59 volumio volumio[822]: error: updateQueue error: null Oct 26 15:56:59 volumio volumio[822]: error: updateQueue error: null Oct 26 15:56:59 volumio volumio[822]: info: ------------------------------ 44ms Oct 26 15:56:59 volumio volumio[822]: info: ------------------------------ 10ms Oct 26 15:56:59 volumio volumio[822]: info: ------------------------------ 7ms Oct 26 15:57:00 volumio volumio[822]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 15:57:00 volumio volumio[822]: TypeError: Cannot read property 'replace' of undefined Oct 26 15:57:00 volumio volumio[822]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Oct 26 15:57:00 volumio volumio[822]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Oct 26 15:57:00 volumio volumio[822]: at listOnTimeout (internal/timers.js:554:17) Oct 26 15:57:00 volumio volumio[822]: at processTimers (internal/timers.js:497:7) Oct 26 15:57:00 volumio volumio[822]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 15:57:01 volumio sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 15:56 Oct 26 15:57:01 volumio sudo[1441]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"