[SOLVED] Volumio 2 Won't Scan Library

I have mounted a network share in Volumio 2; however, it won’t scan my library. Volumio 2’s web GUI indicated the share is mounted successfully. I have mounted it successfully via CIFS and NFS; however when I select “Update” and “Rescan” nothing happens. I am able to log in vis SSH and verify the share mounted successfully (/mnt/NAS/share/), and I can navigate to the files and see all of my music through the shell. I can also successfully mount the share in Windows and access all of my music. My share is hosted on an Openmediavault NAS. Any suggestions?

Try having a look at the system logs when you try to rescan the library … run ‘sudo journalctl -f’ from an ssh session, before trying to rescan.

My log file is as follows after I run “sudo journalctl -f”. It ran quickly until it just started repeating the same thing.

– Logs begin at Tue 2017-06-13 19:31:16 UTC. –
Jul 01 12:36:03 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 01 12:36:03 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 01 12:36:03 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 01 12:36:03 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 01 12:36:03 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 01 12:36:03 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:03 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jul 01 12:36:07 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:08 volumio sudo[26012]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Jul 01 12:36:08 volumio sudo[26012]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Jul 01 12:36:11 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573055] ControllerMpd::sendMpdCommand update
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573056] sending command…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573060] ControllerMpd::sendMpdCommand status
Jul 01 12:36:13 volumio volumio[875]: info: Mpd Status Update: update
Jul 01 12:36:13 volumio volumio[875]: info:
Jul 01 12:36:13 volumio volumio[875]: [1498912573063] ---------------------------- MPD announces state update
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573065] parsing response…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573067] sending command…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573069] ControllerMpd::getState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573071] ControllerMpd::sendMpdCommand status
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573072] sending command…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573076] ControllerMpd::sendMpdCommand status
Jul 01 12:36:13 volumio volumio[875]: info: Mpd Status Update: update
Jul 01 12:36:13 volumio volumio[875]: info:
Jul 01 12:36:13 volumio volumio[875]: [1498912573079] ---------------------------- MPD announces state update
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573081] sending command…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573083] ControllerMpd::getState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573084] ControllerMpd::sendMpdCommand status
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573086] parsing response…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573088] parsing response…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573090] sending command…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573093] ControllerMpd::parseState
Jul 01 12:36:13 volumio volumio[875]: info: Command Router : Notfying DB Updatefalse
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573152] ControllerMpd::parseState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573154] ControllerMpd::sendMpdCommand playlistinfo
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573155] sending command…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573160] parsing response…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573162] parsing response…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573164] parsing response…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573166] ControllerMpd::parseState
Jul 01 12:36:13 volumio volumio[875]: info: Command Router : Notfying DB Updatefalse
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573207] ControllerMpd::parseState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573208] ControllerMpd::sendMpdCommand playlistinfo
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573210] ControllerMpd::parseTrackInfo
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573211] sending command…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573213] ControllerMpd::pushState
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::servicePushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573215] CoreStateMachine::syncState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573216] CorePlayQueue::getTrack 0
Jul 01 12:36:13 volumio volumio[875]: info: STATE SERVICE {“status”:“stop”,“position”:0,“seek”:null,“duration”:null,“samplerate”:null,“bitdepth”:null,“channels”:null,“random”:false,“updatedb”:false,“repeat”:false,“isStreaming”:false,“title”:“Tenth Avenue North - Worn”,“artist”:“Air1”,“album”:null,“uri”:“http://emf.mp3.miisolutions.net/a1/air1_web_tunein",“trackType”:"net/a1/air1_web_tunein”}
Jul 01 12:36:13 volumio volumio[875]: info: CURRENT POSITION 0
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573219] CoreStateMachine::syncState stateService stop
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573220] CoreStateMachine::syncState currentStatus stop
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573221] CoreStateMachine::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573222] CoreStateMachine::getState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573223] CorePlayQueue::getTrack 0
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::volumioPushState
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573226] interfaceApi::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573227] InterfaceWebUI::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573263] No code
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573264] CoreStateMachine::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573266] CoreStateMachine::getState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573267] CorePlayQueue::getTrack 0
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::volumioPushState
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573271] interfaceApi::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573273] InterfaceWebUI::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573310] ------------------------------ 248ms
Jul 01 12:36:13 volumio volumio[875]: info: Pushing Favourites {“service”:“mpd”,“uri”:"",“favourite”:false}
Jul 01 12:36:13 volumio volumio[875]: info: Pushing Favourites {“service”:“mpd”,“uri”:"",“favourite”:false}
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573342] parsing response…
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573344] ControllerMpd::parseTrackInfo
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573345] ControllerMpd::pushState
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::servicePushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573347] CoreStateMachine::syncState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573349] CorePlayQueue::getTrack 0
Jul 01 12:36:13 volumio volumio[875]: info: STATE SERVICE {“status”:“stop”,“position”:0,“seek”:null,“duration”:null,“samplerate”:null,“bitdepth”:null,“channels”:null,“random”:false,“updatedb”:false,“repeat”:false,“isStreaming”:false,“title”:“Tenth Avenue North - Worn”,“artist”:“Air1”,“album”:null,“uri”:“http://emf.mp3.miisolutions.net/a1/air1_web_tunein",“trackType”:"net/a1/air1_web_tunein”}
Jul 01 12:36:13 volumio volumio[875]: info: CURRENT POSITION 0
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573352] CoreStateMachine::syncState stateService stop
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573353] CoreStateMachine::syncState currentStatus stop
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573354] CoreStateMachine::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573355] CoreStateMachine::getState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573356] CorePlayQueue::getTrack 0
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::volumioPushState
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573361] interfaceApi::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573362] InterfaceWebUI::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573396] No code
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573397] CoreStateMachine::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573398] CoreStateMachine::getState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573403] CorePlayQueue::getTrack 0
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::volumioPushState
Jul 01 12:36:13 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573408] interfaceApi::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573410] InterfaceWebUI::pushState
Jul 01 12:36:13 volumio volumio[875]: info: [1498912573447] ------------------------------ 369ms
Jul 01 12:36:13 volumio volumio[875]: info: Pushing Favourites {“service”:“mpd”,“uri”:"",“favourite”:false}
Jul 01 12:36:13 volumio volumio[875]: info: Pushing Favourites {“service”:“mpd”,“uri”:"",“favourite”:false}
Jul 01 12:36:15 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:19 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:23 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:27 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:31 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:35 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:39 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 01 12:36:43 volumio volumio[875]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats

The log for rescan looked about the same. Hope this helps.

It looks as though it is scanning … that’s the bit where you say it keeps repeating. Are you sure that the number of tracks shown (in the UI) is not increasing during this time?

Yes. The UI still indicates 0 Artists, 0 Albums, 0 Tracks, and 0:00:00 Playtime

SOLVED…
I got it working. Although the account I mounted the drive under had read/execute permissions to the share, I had to grant read/execute permissions to other (owner, user group, and other). Previously, owner had full permissions, the user group had read/execute, and other had none.

Good, glad you have sorted it :slight_smile: So it was indeed scanning, but unable to actually read anything.