-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sun 2024-04-21 22:04:24 CST. -- Apr 21 22:03:00 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 21 22:03:00 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 21 22:03:02 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:06 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:10 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:14 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:18 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Apr 21 22:03:18 alex volumio[926]: info: Adding a new share Apr 21 22:03:18 alex volumio[926]: info: No correspondence found in configuration for share alex on IP 192.168.8.102 Apr 21 22:03:18 alex sudo[1309]: 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.8.102/\\\\Laptop-85g4t9g5\\g /mnt/NAS/alex Apr 21 22:03:18 alex sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:03:18 alex kernel: Key type cifs.spnego registered Apr 21 22:03:18 alex kernel: Key type cifs.idmap registered Apr 21 22:03:18 alex 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. Apr 21 22:03:18 alex kernel: CIFS: Attempting to mount \\192.168.8.102\ Apr 21 22:03:18 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:18 alex sudo[1309]: pam_unix(sudo:session): session closed for user root Apr 21 22:03:18 alex kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Apr 21 22:03:18 alex kernel: CIFS: VFS: \\192.168.8.102 Send error in SessSetup = -13 Apr 21 22:03:18 alex kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Apr 21 22:03:18 alex volumio[926]: info: Permission denied for alex on IP 192.168.8.102 Apr 21 22:03:19 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 21 22:03:22 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:26 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:30 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:30 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Apr 21 22:03:30 alex volumio[926]: info: Share alex successfully unmounted Apr 21 22:03:30 alex sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=alex,password=a25494369,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.8.102/\\\\Laptop-85g4t9g5\\g /mnt/NAS/alex Apr 21 22:03:30 alex sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:03:30 alex kernel: CIFS: Attempting to mount \\192.168.8.102\ Apr 21 22:03:30 alex sudo[1339]: pam_unix(sudo:session): session closed for user root Apr 21 22:03:30 alex kernel: CIFS: VFS: cifs_mount failed w/return code = -22 Apr 21 22:03:30 alex volumio[926]: info: An error occurred mounting the new share. Rolling back configuration Apr 21 22:03:31 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 21 22:03:34 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:38 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 21 22:03:40 alex volumio[926]: info: CoreCommandRouter::volumioGetState Apr 21 22:03:40 alex volumio[926]: info: CorePlayQueue::getTrack 0 Apr 21 22:03:53 alex volumio[926]: info: CoreCommandRouter::volumioGetQueue Apr 21 22:03:53 alex volumio[926]: info: CoreStateMachine::getQueue Apr 21 22:03:53 alex volumio[926]: info: CorePlayQueue::getQueue Apr 21 22:03:58 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 21 22:03:58 alex volumio[926]: info: Preload queue cleared Apr 21 22:04:05 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 21 22:04:05 alex volumio[926]: info: CURURI: music-library Apr 21 22:04:05 alex volumio[926]: error: Failed LSINFO: null Apr 21 22:04:05 alex volumio[926]: info: Preload queue cleared Apr 21 22:04:10 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 21 22:04:10 alex volumio[926]: info: Preload queue cleared Apr 21 22:04:13 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 21 22:04:13 alex volumio[926]: info: TuneIn handleBrowseUri: tunein/location Apr 21 22:04:14 alex volumio[926]: info: Preload queue cleared Apr 21 22:04:15 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 21 22:04:15 alex volumio[926]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101216 Apr 21 22:04:15 alex volumio[926]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101216 Apr 21 22:04:16 alex volumio[926]: info: Preload queue cleared Apr 21 22:04:18 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 21 22:04:18 alex volumio[926]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101302 Apr 21 22:04:18 alex volumio[926]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101302 Apr 21 22:04:18 alex volumio[926]: info: Preload queue cleared Apr 21 22:04:18 alex volumio[926]: info: Preload queue cleared Apr 21 22:04:19 alex sudo[971]: pam_unix(sudo:session): session closed for user root Apr 21 22:04:19 alex volumio[926]: info: Cannot mount NAS ALEX at system boot, trial number 1 ,retrying in 5 seconds Apr 21 22:04:21 alex volumio[926]: info: Preload queue cleared Apr 21 22:04:21 alex volumio[926]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 22:04:21 alex volumio[926]: info: CoreStateMachine::ClearQueue Apr 21 22:04:21 alex volumio[926]: info: CoreStateMachine::stop Apr 21 22:04:21 alex volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:04:21 alex volumio[926]: info: CorePlayQueue::clearPlayQueue Apr 21 22:04:21 alex volumio[926]: info: CorePlayQueue::saveQueue Apr 21 22:04:21 alex volumio[926]: info: CoreCommandRouter::volumioPushQueue Apr 21 22:04:21 alex volumio[926]: info: CoreStateMachine::addQueueItems Apr 21 22:04:21 alex volumio[926]: info: CorePlayQueue::addQueueItems Apr 21 22:04:21 alex volumio[926]: info: Preload queue cleared Apr 21 22:04:21 alex volumio[926]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s112541 Apr 21 22:04:21 alex volumio[926]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 21 22:04:22 alex volumio[926]: info: CoreCommandRouter::volumioPushQueue Apr 21 22:04:22 alex volumio[926]: info: CorePlayQueue::saveQueue Apr 21 22:04:22 alex volumio[926]: info: CoreStateMachine::updateTrackBlock Apr 21 22:04:22 alex volumio[926]: info: CorePlayQueue::getTrackBlock Apr 21 22:04:22 alex volumio[926]: info: CoreCommandRouter::volumioPlay Apr 21 22:04:22 alex volumio[926]: info: CoreStateMachine::play index 0 Apr 21 22:04:22 alex volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:04:22 alex volumio[926]: info: CoreStateMachine::stop Apr 21 22:04:22 alex volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:04:22 alex volumio[926]: info: CoreStateMachine::play index undefined Apr 21 22:04:22 alex volumio[926]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:04:22 alex volumio[926]: info: CorePlayQueue::getTrack 0 Apr 21 22:04:22 alex volumio[926]: info: CoreStateMachine::startPlaybackTimer Apr 21 22:04:22 alex volumio[926]: info: CorePlayQueue::getTrack 0 Apr 21 22:04:22 alex volumio[926]: info: [1713708262050] ControllerWebradio::clearAddPlayTrack Apr 21 22:04:22 alex volumio[926]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 22:04:22 alex volumio[926]: info: sendMpdCommand stop took 3 milliseconds Apr 21 22:04:22 alex volumio[926]: verbose: ControllerMpd::sendMpdCommand clear Apr 21 22:04:22 alex volumio[926]: info: Apr 21 22:04:22 alex volumio[926]: ---------------------------- MPD announces system playlist update Apr 21 22:04:22 alex volumio[926]: info: Ignoring MPD Status Update Apr 21 22:04:22 alex volumio[926]: info: sendMpdCommand clear took 2 milliseconds Apr 21 22:04:22 alex volumio[926]: verbose: ControllerMpd::sendMpdCommand load "mms://59.120.150.141:11882/" Apr 21 22:04:22 alex volumio[926]: info: Apr 21 22:04:22 alex volumio[926]: ---------------------------- MPD announces system playlist update Apr 21 22:04:22 alex volumio[926]: info: Ignoring MPD Status Update Apr 21 22:04:22 alex volumio[926]: info: Apr 21 22:04:22 alex volumio[926]: ---------------------------- MPD announces system playlist update Apr 21 22:04:22 alex volumio[926]: info: Ignoring MPD Status Update Apr 21 22:04:22 alex volumio[926]: error: updateQueue error: null Apr 21 22:04:22 alex volumio[926]: info: ------------------------------ 5ms Apr 21 22:04:22 alex volumio[926]: error: Upnp client error: Error: This socket has been ended by the other party Apr 21 22:04:23 alex volumio[926]: verbose: ControllerMpd::sendMpdCommand add "mms://59.120.150.141:11882/" Apr 21 22:04:23 alex volumio[926]: error: updateQueue error: null Apr 21 22:04:23 alex volumio[926]: error: updateQueue error: null Apr 21 22:04:23 alex volumio[926]: info: Apr 21 22:04:23 alex volumio[926]: ---------------------------- MPD announces system playlist update Apr 21 22:04:23 alex volumio[926]: info: Ignoring MPD Status Update Apr 21 22:04:23 alex volumio[926]: info: ------------------------------ 1651ms Apr 21 22:04:23 alex volumio[926]: info: ------------------------------ 1651ms Apr 21 22:04:23 alex volumio[926]: info: sendMpdCommand add "mms://59.120.150.141:11882/" took 2 milliseconds Apr 21 22:04:23 alex volumio[926]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 21 22:04:23 alex volumio[926]: verbose: ControllerMpd::sendMpdCommand play Apr 21 22:04:23 alex volumio[926]: info: Apr 21 22:04:23 alex volumio[926]: ---------------------------- MPD announces system playlist update Apr 21 22:04:23 alex volumio[926]: info: Ignoring MPD Status Update Apr 21 22:04:23 alex volumio[926]: info: Apr 21 22:04:23 alex volumio[926]: ---------------------------- MPD announces system playlist update Apr 21 22:04:23 alex volumio[926]: info: Ignoring MPD Status Update Apr 21 22:04:23 alex volumio[926]: info: ------------------------------ 5ms Apr 21 22:04:23 alex volumio[926]: info: sendMpdCommand play took 4 milliseconds Apr 21 22:04:23 alex volumio[926]: info: ------------------------------ 4ms Apr 21 22:04:23 alex volumio[926]: info: ------------------------------ 3ms Apr 21 22:04:24 alex volumio[926]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 22:04:24 alex volumio[926]: TypeError: Cannot read property 'replace' of undefined Apr 21 22:04:24 alex volumio[926]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Apr 21 22:04:24 alex volumio[926]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Apr 21 22:04:24 alex volumio[926]: at listOnTimeout (internal/timers.js:554:17) Apr 21 22:04:24 alex volumio[926]: at processTimers (internal/timers.js:497:7) Apr 21 22:04:24 alex volumio[926]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 22:04:24 alex sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 22:03 Apr 21 22:04:24 alex sudo[1405]: 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"