-- Logs begin at Sun 2024-03-24 21:42:25 CST, end at Mon 2025-04-28 19:34:28 CST. -- Apr 28 19:33:01 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:04 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Apr 28 19:33:04 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Apr 28 19:33:04 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Apr 28 19:33:04 volumio volumio[943]: info: Refreshing Cached IP Addresses Apr 28 19:33:04 volumio sudo[2363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 28 19:33:04 volumio sudo[2363]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 19:33:04 volumio sudo[2363]: pam_unix(sudo:session): session closed for user root Apr 28 19:33:04 volumio sudo[2366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 19:33:04 volumio sudo[2366]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 19:33:04 volumio sudo[2366]: pam_unix(sudo:session): session closed for user root Apr 28 19:33:04 volumio volumio[943]: error: Upnp client error: Error: This socket has been ended by the other party Apr 28 19:33:04 volumio sudo[2368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 19:33:04 volumio sudo[2368]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 19:33:04 volumio sudo[2368]: pam_unix(sudo:session): session closed for user root Apr 28 19:33:05 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 28 19:33:05 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:05 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 28 19:33:05 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 28 19:33:08 volumio volumio[943]: info: Error browsing device NAS832370 for shares : Error: Command failed: echo volumio | smbclient -L NAS832370.local. Apr 28 19:33:08 volumio volumio[943]: Unable to initialize messaging context Apr 28 19:33:09 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:12 volumio ntpd[654]: Soliciting pool server 198.18.7.56 Apr 28 19:33:13 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:14 volumio sudo[2406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 19:33:14 volumio sudo[2406]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 19:33:14 volumio sudo[2406]: pam_unix(sudo:session): session closed for user root Apr 28 19:33:14 volumio sudo[2409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 19:33:14 volumio sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 19:33:14 volumio sudo[2409]: pam_unix(sudo:session): session closed for user root Apr 28 19:33:14 volumio sudo[2412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 28 19:33:14 volumio sudo[2412]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 19:33:14 volumio sudo[2412]: pam_unix(sudo:session): session closed for user root Apr 28 19:33:14 volumio volumio[943]: info: Upmpdcli Daemon Started Apr 28 19:33:17 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:18 volumio ntpd[654]: Soliciting pool server 198.18.7.55 Apr 28 19:33:21 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:21 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 28 19:33:21 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 28 19:33:22 volumio ntpd[654]: Soliciting pool server 198.18.7.57 Apr 28 19:33:23 volumio volumio[943]: info: Error browsing device NAS832370 for shares : Error: Command failed: echo volumio | smbclient -L NAS832370.local. Apr 28 19:33:23 volumio volumio[943]: Unable to initialize messaging context Apr 28 19:33:25 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:26 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 28 19:33:26 volumio volumio[943]: verbose: ControllerMpd::sendMpdCommand rescan Apr 28 19:33:26 volumio volumio[943]: verbose: ControllerMpd::sendMpdCommand status Apr 28 19:33:26 volumio volumio[943]: info: Apr 28 19:33:26 volumio volumio[943]: ---------------------------- MPD announces state update: update Apr 28 19:33:26 volumio volumio[943]: info: sendMpdCommand rescan took 6 milliseconds Apr 28 19:33:26 volumio volumio[943]: info: ControllerMpd::getState Apr 28 19:33:26 volumio volumio[943]: verbose: ControllerMpd::sendMpdCommand status Apr 28 19:33:26 volumio volumio[943]: info: sendMpdCommand status took 11 milliseconds Apr 28 19:33:26 volumio volumio[943]: info: sendMpdCommand status took 7 milliseconds Apr 28 19:33:26 volumio volumio[943]: verbose: ControllerMpd::parseState Apr 28 19:33:26 volumio volumio[943]: info: Command Router : Notfying DB Updatetrue Apr 28 19:33:26 volumio volumio[943]: verbose: ControllerMpd::parseState Apr 28 19:33:26 volumio volumio[943]: info: ControllerMpd::pushState Apr 28 19:33:26 volumio volumio[943]: info: CoreCommandRouter::servicePushState Apr 28 19:33:26 volumio volumio[943]: info: CoreStateMachine::pushState Apr 28 19:33:26 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:26 volumio volumio[943]: info: CoreCommandRouter::volumioPushState Apr 28 19:33:26 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:26 volumio volumio[943]: 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} Apr 28 19:33:26 volumio volumio[943]: verbose: CURRENT POSITION 0 Apr 28 19:33:26 volumio volumio[943]: info: CoreStateMachine::syncState stateService stop Apr 28 19:33:26 volumio volumio[943]: info: CoreStateMachine::syncState currentStatus stop Apr 28 19:33:26 volumio volumio[943]: info: CoreStateMachine::pushState Apr 28 19:33:26 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:26 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 19:33:26 volumio volumio[943]: info: CoreCommandRouter::volumioPushState Apr 28 19:33:26 volumio volumio[943]: info: No code Apr 28 19:33:26 volumio volumio[943]: info: CoreStateMachine::pushState Apr 28 19:33:26 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:26 volumio volumio[943]: info: CoreCommandRouter::volumioPushState Apr 28 19:33:26 volumio volumio[943]: info: ------------------------------ 86ms Apr 28 19:33:27 volumio volumio[943]: info: MPD Database updated - AlbumList cache refreshed Apr 28 19:33:27 volumio volumio[943]: info: Apr 28 19:33:27 volumio volumio[943]: ---------------------------- MPD announces state update: database Apr 28 19:33:27 volumio volumio[943]: verbose: ControllerMpd::sendMpdCommand status Apr 28 19:33:27 volumio volumio[943]: info: Apr 28 19:33:27 volumio volumio[943]: ---------------------------- MPD announces state update: update Apr 28 19:33:27 volumio volumio[943]: info: ControllerMpd::getState Apr 28 19:33:27 volumio volumio[943]: verbose: ControllerMpd::sendMpdCommand status Apr 28 19:33:27 volumio volumio[943]: info: ControllerMpd::getState Apr 28 19:33:27 volumio volumio[943]: verbose: ControllerMpd::sendMpdCommand status Apr 28 19:33:27 volumio volumio[943]: info: sendMpdCommand status took 8 milliseconds Apr 28 19:33:27 volumio volumio[943]: info: sendMpdCommand status took 7 milliseconds Apr 28 19:33:27 volumio volumio[943]: info: sendMpdCommand status took 6 milliseconds Apr 28 19:33:27 volumio volumio[943]: verbose: ControllerMpd::parseState Apr 28 19:33:27 volumio volumio[943]: info: Command Router : Notfying DB Updatefalse Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::Close All Modals sent Apr 28 19:33:27 volumio volumio[943]: verbose: ControllerMpd::parseState Apr 28 19:33:27 volumio volumio[943]: verbose: ControllerMpd::parseState Apr 28 19:33:27 volumio volumio[943]: info: ControllerMpd::pushState Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::servicePushState Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::pushState Apr 28 19:33:27 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::volumioPushState Apr 28 19:33:27 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:27 volumio volumio[943]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 28 19:33:27 volumio volumio[943]: verbose: CURRENT POSITION 0 Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::syncState stateService stop Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::syncState currentStatus stop Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::pushState Apr 28 19:33:27 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::volumioPushState Apr 28 19:33:27 volumio volumio[943]: info: No code Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::pushState Apr 28 19:33:27 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::volumioPushState Apr 28 19:33:27 volumio volumio[943]: info: ControllerMpd::pushState Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::servicePushState Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::pushState Apr 28 19:33:27 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::volumioPushState Apr 28 19:33:27 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:27 volumio volumio[943]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 28 19:33:27 volumio volumio[943]: verbose: CURRENT POSITION 0 Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::syncState stateService stop Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::syncState currentStatus stop Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::pushState Apr 28 19:33:27 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::volumioPushState Apr 28 19:33:27 volumio volumio[943]: info: No code Apr 28 19:33:27 volumio volumio[943]: info: CoreStateMachine::pushState Apr 28 19:33:27 volumio volumio[943]: info: CorePlayQueue::getTrack 0 Apr 28 19:33:27 volumio volumio[943]: info: CoreCommandRouter::volumioPushState Apr 28 19:33:27 volumio volumio[943]: info: ------------------------------ 82ms Apr 28 19:33:27 volumio volumio[943]: info: ------------------------------ 84ms Apr 28 19:33:29 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:33 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:37 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:41 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:45 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:49 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:53 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:33:57 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:34:01 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:34:05 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:34:09 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:34:11 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Apr 28 19:34:11 volumio volumio[943]: info: Adding a new share Apr 28 19:34:13 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:34:15 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 28 19:34:15 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 28 19:34:16 volumio ntpd[654]: Soliciting pool server 198.18.7.56 Apr 28 19:34:17 volumio volumio[943]: info: Error browsing device NAS832370 for shares : Error: Command failed: echo volumio | smbclient -L NAS832370.local. Apr 28 19:34:17 volumio volumio[943]: Unable to initialize messaging context Apr 28 19:34:17 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:34:21 volumio volumio[943]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 19:34:22 volumio ntpd[654]: Soliciting pool server 198.18.7.55 Apr 28 19:34:23 volumio volumio[943]: info: Cannot mount NAS NAS1 at system boot, trial number 3 ,retrying in 5 seconds Apr 28 19:34:26 volumio ntpd[654]: Soliciting pool server 198.18.7.57 Apr 28 19:34:28 volumio volumio[943]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 19:34:28 volumio volumio[943]: TypeError: Cannot read property 'replace' of undefined Apr 28 19:34:28 volumio volumio[943]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Apr 28 19:34:28 volumio volumio[943]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Apr 28 19:34:28 volumio volumio[943]: at listOnTimeout (internal/timers.js:554:17) Apr 28 19:34:28 volumio volumio[943]: at processTimers (internal/timers.js:497:7) Apr 28 19:34:28 volumio volumio[943]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 19:34:28 volumio sudo[2538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 19:33 Apr 28 19:34:28 volumio sudo[2538]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"