-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-04-23 14:04:30 CEST. -- Apr 23 14:03:01 radio wpa_supplicant[864]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 14:03:01 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 14:03:02 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Apr 23 14:03:03 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 23 14:03:03 radio volumio[998]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 23 14:03:03 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 23 14:03:03 radio volumio[998]: info: Received Get System Version Apr 23 14:03:03 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 14:03:03 radio volumio[998]: info: Received Get System Info Apr 23 14:03:03 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 14:03:03 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 14:03:03 radio volumio[998]: info: Discovery: Getting this device information Apr 23 14:03:03 radio volumio[998]: info: CoreCommandRouter::volumioGetState Apr 23 14:03:03 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:03:03 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 14:03:03 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 23 14:03:05 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 14:03:09 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 14:03:13 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 14:03:17 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 23 14:03:20 radio volumio[998]: info: CoreCommandRouter::volumioGetState Apr 23 14:03:20 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:03:22 radio volumio[998]: info: CoreCommandRouter::volumioGetQueue Apr 23 14:03:22 radio volumio[998]: info: CoreStateMachine::getQueue Apr 23 14:03:22 radio volumio[998]: info: CorePlayQueue::getQueue Apr 23 14:03:23 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 14:03:23 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 14:03:23 radio volumio[998]: info: Discovery: Getting this device information Apr 23 14:03:23 radio volumio[998]: info: CoreCommandRouter::volumioGetState Apr 23 14:03:23 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:03:23 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 14:03:24 radio wpa_supplicant[864]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 14:03:24 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 23 14:03:24 radio volumio[998]: info: CURURI: music-library Apr 23 14:03:24 radio volumio[998]: info: Preload queue cleared Apr 23 14:03:28 radio volumio[998]: verbose: New Socket.io Connection to 192.168.101.26:3000 from 192.168.101.64 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Apr 23 14:03:29 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 23 14:03:29 radio volumio[998]: info: CURURI: music-library/INTERNAL Apr 23 14:03:29 radio volumio[998]: info: Preload queue cleared Apr 23 14:03:33 radio wpa_supplicant[864]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 14:03:47 radio sshd[1721]: Accepted password for volumio from 192.168.101.75 port 41562 ssh2 Apr 23 14:03:47 radio sshd[1721]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Apr 23 14:03:47 radio systemd[1]: Created slice User Slice of UID 1000. Apr 23 14:03:48 radio systemd[1]: Starting User Runtime Directory /run/user/1000... Apr 23 14:03:48 radio systemd-logind[629]: New session 1 of user volumio. Apr 23 14:03:48 radio systemd[1]: Started User Runtime Directory /run/user/1000. Apr 23 14:03:48 radio systemd[1]: Starting User Manager for UID 1000... Apr 23 14:03:48 radio systemd[1793]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Apr 23 14:03:48 radio systemd[1793]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Apr 23 14:03:48 radio systemd[1793]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Apr 23 14:03:48 radio systemd[1793]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Apr 23 14:03:48 radio systemd[1793]: Listening on GnuPG cryptographic agent and passphrase cache. Apr 23 14:03:48 radio systemd[1793]: Starting D-Bus User Message Bus Socket. Apr 23 14:03:48 radio systemd[1793]: Reached target Timers. Apr 23 14:03:48 radio systemd[1793]: Listening on GnuPG network certificate management daemon. Apr 23 14:03:48 radio systemd[1793]: Listening on Sound System. Apr 23 14:03:48 radio systemd[1793]: Reached target Paths. Apr 23 14:03:48 radio systemd[1793]: Listening on D-Bus User Message Bus Socket. Apr 23 14:03:48 radio systemd[1793]: Reached target Sockets. Apr 23 14:03:48 radio systemd[1793]: Reached target Basic System. Apr 23 14:03:48 radio systemd[1793]: Reached target Default. Apr 23 14:03:48 radio systemd[1793]: Startup finished in 326ms. Apr 23 14:03:48 radio systemd[1]: Started User Manager for UID 1000. Apr 23 14:03:48 radio systemd[1]: Started Session 1 of user volumio. Apr 23 14:03:49 radio wpa_supplicant[864]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 14:03:58 radio wpa_supplicant[864]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 14:03:59 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 14:03:59 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 14:03:59 radio volumio[998]: info: Discovery: Getting this device information Apr 23 14:03:59 radio volumio[998]: info: CoreCommandRouter::volumioGetState Apr 23 14:03:59 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:03:59 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 14:04:01 radio volumio[998]: verbose: New Socket.io Connection to 192.168.101.26:3000 from 192.168.101.64 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Apr 23 14:04:11 radio 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. Apr 23 14:04:11 radio systemd[1]: Starting Preprocess NFS configuration... Apr 23 14:04:11 radio systemd[1]: Reached target Host and Network Name Lookups. Apr 23 14:04:11 radio systemd[1]: nfs-config.service: Succeeded. Apr 23 14:04:11 radio systemd[1]: Started Preprocess NFS configuration. Apr 23 14:04:11 radio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Apr 23 14:04:11 radio systemd[1]: Starting Notify NFS peers of a restart... Apr 23 14:04:11 radio sm-notify[1857]: Version 1.3.3 starting Apr 23 14:04:11 radio systemd[1]: rpc-statd-notify.service: Succeeded. Apr 23 14:04:11 radio systemd[1]: Started Notify NFS peers of a restart. Apr 23 14:04:11 radio rpc.statd[1858]: Version 1.3.3 starting Apr 23 14:04:11 radio rpc.statd[1858]: Flags: TI-RPC Apr 23 14:04:11 radio rpc.statd[1858]: Failed to read /var/lib/nfs/state: Success Apr 23 14:04:11 radio rpc.statd[1858]: Initializing NSM state Apr 23 14:04:11 radio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Apr 23 14:04:14 radio wpa_supplicant[864]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 14:04:15 radio sshd[1859]: Accepted publickey for volumio from 192.168.101.75 port 55138 ssh2: RSA SHA256:KLqpU/2/8xZfGODpmwlHAUkYMMySOLCOO0uU7fRKc9o Apr 23 14:04:15 radio sshd[1859]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Apr 23 14:04:15 radio systemd-logind[629]: New session 3 of user volumio. Apr 23 14:04:15 radio systemd[1]: Started Session 3 of user volumio. Apr 23 14:04:21 radio volumio[998]: info: MPD Database updated - AlbumList cache refreshed Apr 23 14:04:21 radio volumio[998]: info: Apr 23 14:04:21 radio volumio[998]: ---------------------------- MPD announces state update: database Apr 23 14:04:21 radio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Apr 23 14:04:21 radio volumio[998]: info: Apr 23 14:04:21 radio volumio[998]: ---------------------------- MPD announces state update: update Apr 23 14:04:21 radio volumio[998]: info: ControllerMpd::getState Apr 23 14:04:21 radio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Apr 23 14:04:21 radio volumio[998]: info: ControllerMpd::getState Apr 23 14:04:21 radio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Apr 23 14:04:21 radio volumio[998]: info: MPD Database updated - AlbumList cache refreshed Apr 23 14:04:21 radio volumio[998]: info: Apr 23 14:04:21 radio volumio[998]: ---------------------------- MPD announces state update: database Apr 23 14:04:21 radio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Apr 23 14:04:21 radio volumio[998]: info: Apr 23 14:04:21 radio volumio[998]: ---------------------------- MPD announces state update: update Apr 23 14:04:21 radio volumio[998]: info: ControllerMpd::getState Apr 23 14:04:21 radio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Apr 23 14:04:21 radio volumio[998]: info: ControllerMpd::getState Apr 23 14:04:21 radio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Apr 23 14:04:21 radio volumio[998]: info: MPD Database updated - AlbumList cache refreshed Apr 23 14:04:21 radio volumio[998]: info: Apr 23 14:04:21 radio volumio[998]: ---------------------------- MPD announces state update: database Apr 23 14:04:21 radio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Apr 23 14:04:21 radio volumio[998]: info: Apr 23 14:04:21 radio volumio[998]: ---------------------------- MPD announces state update: update Apr 23 14:04:21 radio volumio[998]: info: ControllerMpd::getState Apr 23 14:04:21 radio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Apr 23 14:04:21 radio volumio[998]: info: ControllerMpd::getState Apr 23 14:04:21 radio volumio[998]: verbose: ControllerMpd::sendMpdCommand status Apr 23 14:04:22 radio volumio[998]: info: sendMpdCommand status took 1249 milliseconds Apr 23 14:04:22 radio volumio[998]: info: sendMpdCommand status took 1248 milliseconds Apr 23 14:04:22 radio volumio[998]: info: sendMpdCommand status took 1247 milliseconds Apr 23 14:04:22 radio volumio[998]: verbose: ControllerMpd::parseState Apr 23 14:04:24 radio wpa_supplicant[864]: RRM: Ignoring radio measurement request: Not RRM network Apr 23 14:04:24 radio volumio[998]: info: Command Router : Notfying DB Updatefalse Apr 23 14:04:24 radio volumio[998]: info: CoreCommandRouter::Close All Modals sent Apr 23 14:04:24 radio volumio[998]: verbose: ControllerMpd::parseState Apr 23 14:04:24 radio volumio[998]: verbose: ControllerMpd::parseState Apr 23 14:04:24 radio volumio[998]: info: ControllerMpd::pushState Apr 23 14:04:24 radio volumio[998]: info: CoreCommandRouter::servicePushState Apr 23 14:04:24 radio volumio[998]: info: CoreStateMachine::pushState Apr 23 14:04:24 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:24 radio volumio[998]: info: CoreCommandRouter::volumioPushState Apr 23 14:04:24 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:24 radio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Apr 23 14:04:24 radio volumio[998]: info: ControllerMpd::pushState Apr 23 14:04:24 radio volumio[998]: info: CoreCommandRouter::servicePushState Apr 23 14:04:24 radio volumio[998]: info: CoreStateMachine::pushState Apr 23 14:04:24 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:24 radio volumio[998]: info: CoreCommandRouter::volumioPushState Apr 23 14:04:24 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:24 radio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Apr 23 14:04:24 radio volumio[998]: info: ------------------------------ 3473ms Apr 23 14:04:24 radio volumio[998]: info: ------------------------------ 3474ms Apr 23 14:04:24 radio sudo[1082]: pam_unix(sudo:session): session closed for user root Apr 23 14:04:24 radio volumio[998]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Apr 23 14:04:25 radio volumio[998]: info: sendMpdCommand status took 4342 milliseconds Apr 23 14:04:25 radio volumio[998]: info: sendMpdCommand status took 4341 milliseconds Apr 23 14:04:25 radio volumio[998]: verbose: ControllerMpd::parseState Apr 23 14:04:26 radio volumio[998]: info: Command Router : Notfying DB Updatefalse Apr 23 14:04:26 radio volumio[998]: info: CoreCommandRouter::Close All Modals sent Apr 23 14:04:26 radio volumio[998]: verbose: ControllerMpd::parseState Apr 23 14:04:26 radio volumio[998]: verbose: ControllerMpd::parseState Apr 23 14:04:26 radio volumio[998]: info: ControllerMpd::pushState Apr 23 14:04:26 radio volumio[998]: info: CoreCommandRouter::servicePushState Apr 23 14:04:26 radio volumio[998]: info: CoreStateMachine::pushState Apr 23 14:04:26 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:26 radio volumio[998]: info: CoreCommandRouter::volumioPushState Apr 23 14:04:26 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:26 radio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Apr 23 14:04:26 radio volumio[998]: info: ControllerMpd::pushState Apr 23 14:04:26 radio volumio[998]: info: CoreCommandRouter::servicePushState Apr 23 14:04:26 radio volumio[998]: info: CoreStateMachine::pushState Apr 23 14:04:26 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:26 radio volumio[998]: info: CoreCommandRouter::volumioPushState Apr 23 14:04:26 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:26 radio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Apr 23 14:04:26 radio volumio[998]: info: ------------------------------ 5677ms Apr 23 14:04:26 radio volumio[998]: info: ------------------------------ 5677ms Apr 23 14:04:27 radio volumio[998]: info: sendMpdCommand status took 6386 milliseconds Apr 23 14:04:27 radio volumio[998]: info: sendMpdCommand status took 6386 milliseconds Apr 23 14:04:27 radio volumio[998]: info: sendMpdCommand status took 6385 milliseconds Apr 23 14:04:27 radio volumio[998]: verbose: ControllerMpd::parseState Apr 23 14:04:28 radio volumio[998]: info: Command Router : Notfying DB Updatefalse Apr 23 14:04:28 radio volumio[998]: info: CoreCommandRouter::Close All Modals sent Apr 23 14:04:28 radio volumio[998]: verbose: ControllerMpd::parseState Apr 23 14:04:28 radio volumio[998]: verbose: ControllerMpd::parseState Apr 23 14:04:28 radio volumio[998]: info: ControllerMpd::pushState Apr 23 14:04:28 radio volumio[998]: info: CoreCommandRouter::servicePushState Apr 23 14:04:28 radio volumio[998]: info: CoreStateMachine::pushState Apr 23 14:04:28 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:28 radio volumio[998]: info: CoreCommandRouter::volumioPushState Apr 23 14:04:28 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:28 radio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Apr 23 14:04:28 radio volumio[998]: info: ControllerMpd::pushState Apr 23 14:04:28 radio volumio[998]: info: CoreCommandRouter::servicePushState Apr 23 14:04:28 radio volumio[998]: info: CoreStateMachine::pushState Apr 23 14:04:28 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:28 radio volumio[998]: info: CoreCommandRouter::volumioPushState Apr 23 14:04:28 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:28 radio volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Apr 23 14:04:28 radio volumio[998]: info: ------------------------------ 7535ms Apr 23 14:04:28 radio volumio[998]: info: ------------------------------ 7535ms Apr 23 14:04:28 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 23 14:04:28 radio volumio[998]: info: CURURI: albums:// Apr 23 14:04:28 radio volumio[998]: info: listAlbums - loading Albums from cache Apr 23 14:04:28 radio volumio[998]: info: Preload queue cleared Apr 23 14:04:29 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 14:04:29 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 14:04:29 radio volumio[998]: info: Discovery: Getting this device information Apr 23 14:04:29 radio volumio[998]: info: CoreCommandRouter::volumioGetState Apr 23 14:04:29 radio volumio[998]: info: CorePlayQueue::getTrack 0 Apr 23 14:04:29 radio volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 14:04:29 radio volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 14:04:29 radio volumio[998]: TypeError: Cannot read property 'replace' of undefined Apr 23 14:04:29 radio volumio[998]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Apr 23 14:04:29 radio volumio[998]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Apr 23 14:04:29 radio volumio[998]: at listOnTimeout (internal/timers.js:554:17) Apr 23 14:04:29 radio volumio[998]: at processTimers (internal/timers.js:497:7) Apr 23 14:04:29 radio volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 14:04:30 radio sudo[1918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-23 14:03 Apr 23 14:04:30 radio sudo[1918]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"