So sorry, I didn’t understand what you were asking for (printed the output before mounting, stopped the command and printed the output after mounting ).
So here is a fresh new test with the command launched before mounting and all the output until the mounting stopped :
volumio@volumio:~$ sudo journalctl -f
-- Logs begin at Sat 2016-08-20 16:39:20 UTC. --
Sep 20 13:22:06 volumio systemd[8747]: Reached target Basic System.
Sep 20 13:22:06 volumio systemd[8747]: Starting Default.
Sep 20 13:22:06 volumio systemd[8747]: Reached target Default.
Sep 20 13:22:06 volumio systemd[8747]: Startup finished in 31ms.
Sep 20 13:22:06 volumio systemd[1]: Started User Manager for UID 1000.
Sep 20 13:22:06 volumio sshd[8726]: pam_env(sshd:session): Unable to open env file: /etc/default/locale: No such file or directory
Sep 20 13:22:07 volumio sshd[8726]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Sep 20 13:22:07 volumio sshd[8726]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Sep 20 13:22:18 volumio sudo[8806]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Sep 20 13:22:18 volumio sudo[8806]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Sep 20 13:22:31 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Sep 20 13:22:31 volumio volumio[602]: info: CoreCommandRouter::volumioGetState
Sep 20 13:22:31 volumio volumio[602]: info: [1474377751066] CoreStateMachine::getState
Sep 20 13:22:31 volumio volumio[602]: info: [1474377751068] CorePlayQueue::getTrack 0
Sep 20 13:22:31 volumio volumio[602]: info:
Sep 20 13:22:31 volumio volumio[602]: [1474377751070] ---------------------------- Client requests Volumio state
Sep 20 13:22:31 volumio volumio[602]: info: [1474377751075] Listing playlists
Sep 20 13:22:31 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Sep 20 13:22:31 volumio volumio[602]: Plugin multiroom or method getMultiroom not found
Sep 20 13:22:31 volumio volumio[602]: info:
Sep 20 13:22:31 volumio volumio[602]: [1474377751081] ---------------------------- Client requests Menu Items
Sep 20 13:22:31 volumio volumio[602]: info: [1474377751082] InterfaceWebUI::pushState
Sep 20 13:22:31 volumio volumio[602]: info: [1474377751087] ------------------------------ 23ms
Sep 20 13:22:31 volumio volumio[602]: info: [1474377751095] ------------------------------ 14ms
Sep 20 13:22:38 volumio volumio[602]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 20 13:22:38 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:22:38 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Sep 20 13:22:42 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:22:43 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Sep 20 13:22:46 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:22:50 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:22:54 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:22:58 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:23:02 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:23:06 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare
Sep 20 13:23:07 volumio volumio[602]: info: Adding a new share
Sep 20 13:23:07 volumio volumio[602]: info: No correspondence found in configuration for share Datastation on IP datastation.local
Sep 20 13:23:07 volumio sudo[9012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto //datastation.local/Datastation Drives SMB /mnt/NAS/Datastation
Sep 20 13:23:07 volumio sudo[9012]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 20 13:23:07 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching
Sep 20 13:23:07 volumio kernel: Key type cifs.spnego registered
Sep 20 13:23:07 volumio kernel: Key type cifs.idmap registered
Sep 20 13:23:07 volumio sudo[9012]: pam_unix(sudo:session): session closed for user root
Sep 20 13:23:07 volumio mpd[637]: client: [2] opened from local
Sep 20 13:23:07 volumio mpd[637]: client: [2] closed
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787566] ControllerMpd::sendMpdCommand status
Sep 20 13:23:07 volumio volumio[602]: info: Mpd Status Update: update
Sep 20 13:23:07 volumio volumio[602]: info:
Sep 20 13:23:07 volumio volumio[602]: [1474377787574] ---------------------------- MPD announces state update
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787579] sending command...
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787581] ControllerMpd::getState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787583] ControllerMpd::sendMpdCommand status
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787583] sending command...
Sep 20 13:23:07 volumio mpd[637]: errno: Failed to open 'NAS/Datastation/Media': Permission denied
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787594] Database update started
Sep 20 13:23:07 volumio volumio[602]: COMMON.MY_MUSIC
Sep 20 13:23:07 volumio volumio[602]: [ 'COMMON', 'MY_MUSIC' ]
Sep 20 13:23:07 volumio volumio[602]: COMMON.SCAN_DB
Sep 20 13:23:07 volumio volumio[602]: [ 'COMMON', 'SCAN_DB' ]
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787610] ControllerMpd::sendMpdCommand status
Sep 20 13:23:07 volumio volumio[602]: info: Mpd Status Update: update
Sep 20 13:23:07 volumio volumio[602]: info:
Sep 20 13:23:07 volumio volumio[602]: [1474377787612] ---------------------------- MPD announces state update
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787614] sending command...
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787616] ControllerMpd::getState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787617] ControllerMpd::sendMpdCommand status
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787618] sending command...
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787620] parsing response...
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787622] parsing response...
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787623] parsing response...
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787626] ControllerMpd::parseState
Sep 20 13:23:07 volumio volumio[602]: info: Command Router : Notfying DB Updatefalse
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787630] ControllerMpd::parseState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787631] ControllerMpd::parseState
Sep 20 13:23:07 volumio volumio[602]: info: Command Router : Notfying DB Updatefalse
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787634] ControllerMpd::pushState
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::servicePushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787639] CoreStateMachine::syncState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787640] CorePlayQueue::getTrack 0
Sep 20 13:23:07 volumio volumio[602]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Sep 20 13:23:07 volumio volumio[602]: info: CURRENT POSITION 0
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787642] CoreStateMachine::syncState stateService stop
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787642] CoreStateMachine::syncState currentStatus stop
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787643] CoreStateMachine::pushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787643] CoreStateMachine::getState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787644] CorePlayQueue::getTrack 0
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::volumioPushState
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787646] InterfaceWebUI::pushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787648] No code
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787649] CoreStateMachine::pushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787649] CoreStateMachine::getState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787650] CorePlayQueue::getTrack 0
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::volumioPushState
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787652] InterfaceWebUI::pushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787657] ------------------------------ 84ms
Sep 20 13:23:07 volumio volumio[602]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false}
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787663] parsing response...
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787664] ControllerMpd::parseState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787665] ControllerMpd::pushState
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::servicePushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787666] CoreStateMachine::syncState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787666] CorePlayQueue::getTrack 0
Sep 20 13:23:07 volumio volumio[602]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Sep 20 13:23:07 volumio volumio[602]: info: CURRENT POSITION 0
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787669] CoreStateMachine::syncState stateService stop
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787669] CoreStateMachine::syncState currentStatus stop
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787670] CoreStateMachine::pushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787670] CoreStateMachine::getState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787671] CorePlayQueue::getTrack 0
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::volumioPushState
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787672] InterfaceWebUI::pushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787675] No code
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787675] CoreStateMachine::pushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787676] CoreStateMachine::getState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787676] CorePlayQueue::getTrack 0
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::volumioPushState
Sep 20 13:23:07 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787684] InterfaceWebUI::pushState
Sep 20 13:23:07 volumio volumio[602]: info: [1474377787691] ------------------------------ 80ms
Sep 20 13:23:07 volumio volumio[602]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false}
Sep 20 13:23:07 volumio volumio[602]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false}
Sep 20 13:23:07 volumio volumio[602]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false}
Sep 20 13:23:08 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Sep 20 13:23:10 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:23:14 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:23:18 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Sep 20 13:23:22 volumio volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
So I think the relevant log here is :
Sep 20 13:23:07 volumio mpd[637]: errno: Failed to open 'NAS/Datastation/Media': Permission denied
Any idea why this happens ?
As user volumio
I’m able to browse this folder with no problem, can’t understand why this happens to me but everyone seems to be able to mount his CIFS share
Thanks for your attention !