Hi,
I’m just trying to do my initial setup and I’m having trouble scanning and creating my database. I’m using an odroid C1 with the hifi shield and my music is stored on a ReadyNAS. I can access my music from Windows on the share.
My share seems to be mounting correctly (log is below) because I can go to the mount directory /mnt/NAS/ADARI/MUSIC and I can see the music folders. When I do update or rescan the little box in the top right corner scans from right to left for a few seconds and then it completes but still no music listed in My Music.
My music library is quite large (I guess thats relative) so I can imagine it might take some to scan, but it just doesn’t seem to scan anything.
Also, when it mounts the share it shows the space used, not the total size of the share, so I guess its querying the NAS correctly.
Any suggestions please?
Thanks,
Will
Edit - reading through the forum some more I saw the suggestion for journalctl, so I have added that below. I started journalctl and then hit rescan, the output is listed.
Edit - the scanning did work in a previous version; not sure which version it was a couple of years ago. At that time it would scan and capture part of my database but it seemed to have the problem (reported by other users) that the scan would not complete, it would stop before scanning the entire database.
tail of log (username and password removed) -
volumio@volumio:~$ sudo journalctl|grep -i music
Nov 03 11:54:08 volumio volumio[456]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Nov 03 11:54:09 volumio systemd[1]: Starting Music Player Daemon…
Nov 03 11:54:12 volumio volumio[691]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Nov 03 11:54:12 volumio volumio[721]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Nov 03 11:54:13 volumio volumio[768]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Nov 03 11:54:13 volumio volumio[782]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Nov 03 11:54:14 volumio systemd[1]: Started Music Player Daemon.
Nov 03 11:54:49 volumio volumio[857]: info: Configured plugin music_service/streaming_services cannot be loaded. Removing from configuration
Nov 03 11:54:49 volumio volumio[857]: info: Scanning category music_service
Nov 03 11:54:49 volumio volumio[857]: info: Adding plugin upnp to MyMusic Plugins
Nov 03 11:54:49 volumio volumio[857]: info: Adding plugin airplay_emulation to MyMusic Plugins
Nov 03 11:54:49 volumio volumio[857]: info: Adding plugin upnp_browser to MyMusic Plugins
Nov 03 11:54:51 volumio volumio[857]: info: Loading plugin “my_music”…
Nov 03 11:54:53 volumio sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=xxxx,password=xxxx,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.88.36/music /mnt/NAS/ADARI
Nov 03 11:54:54 volumio volumio[857]: info: [1572782094359] CoreMusicLibrary::Adding element Media Servers
Nov 03 11:54:54 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 03 11:54:54 volumio volumio[857]: info: [1572782094516] CoreMusicLibrary::Adding element Last_100
Nov 03 11:54:54 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 03 11:54:54 volumio volumio[857]: info: [1572782094522] CoreMusicLibrary::Adding element Webradio
Nov 03 11:54:54 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 03 11:54:54 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 03 11:55:00 volumio volumio[857]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14)
Nov 03 11:55:00 volumio volumio[857]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10)
Nov 03 11:55:01 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 03 11:55:09 volumio volumio[857]: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Nov 03 11:55:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Nov 03 11:55:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
volumio@volumio:~$ sudo journalctl -f
– Logs begin at Sun 2019-11-03 11:54:05 UTC. –
Nov 03 12:20:00 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:13 volumio sudo[4013]: pam_unix(sudo:session): session closed for user root
Nov 03 12:20:16 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:20 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:24 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:26 volumio sudo[4573]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Nov 03 12:20:26 volumio sudo[4573]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Nov 03 12:20:28 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::sendMpdCommand rescan
Nov 03 12:20:32 volumio volumio[857]: info: sending command…
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::sendMpdCommand status
Nov 03 12:20:32 volumio volumio[857]: info:
Nov 03 12:20:32 volumio volumio[857]: ---------------------------- MPD announces state update: update
Nov 03 12:20:32 volumio volumio[857]: info: parsing response…
Nov 03 12:20:32 volumio volumio[857]: info: sending command…
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::getState
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::sendMpdCommand status
Nov 03 12:20:32 volumio volumio[857]: info: sending command…
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::sendMpdCommand status
Nov 03 12:20:32 volumio volumio[857]: info:
Nov 03 12:20:32 volumio volumio[857]: ---------------------------- MPD announces state update: update
Nov 03 12:20:32 volumio volumio[857]: info: parsing response…
Nov 03 12:20:32 volumio volumio[857]: info: sending command…
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::getState
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::sendMpdCommand status
Nov 03 12:20:32 volumio volumio[857]: info: parsing response…
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::parseState
Nov 03 12:20:32 volumio volumio[857]: info: Command Router : Notfying DB Updatetrue
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::Close All Modals sent
Nov 03 12:20:32 volumio volumio[857]: info: sending command…
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::parseState
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::pushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::servicePushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::syncState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::pushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::getState
Nov 03 12:20:32 volumio volumio[857]: info: CorePlayQueue::getTrack 0
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::volumioPushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 03 12:20:32 volumio volumio[857]: info: CorePlayQueue::getTrack 0
Nov 03 12:20:32 volumio volumio[857]: 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}
Nov 03 12:20:32 volumio volumio[857]: info: CURRENT POSITION 0
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::pushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::getState
Nov 03 12:20:32 volumio volumio[857]: info: CorePlayQueue::getTrack 0
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::volumioPushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 03 12:20:32 volumio sudo[4591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Nov 03 12:20:32 volumio sudo[4591]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 03 12:20:32 volumio volumio[857]: info: No code
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::pushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::getState
Nov 03 12:20:32 volumio volumio[857]: info: CorePlayQueue::getTrack 0
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::volumioPushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 03 12:20:32 volumio sudo[4591]: pam_unix(sudo:session): session closed for user root
Nov 03 12:20:32 volumio sudo[4594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Nov 03 12:20:32 volumio sudo[4594]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 03 12:20:32 volumio sudo[4594]: pam_unix(sudo:session): session closed for user root
Nov 03 12:20:32 volumio volumio[857]: info: ------------------------------ 181ms
Nov 03 12:20:32 volumio volumio[857]: info: parsing response…
Nov 03 12:20:32 volumio volumio[857]: info: parsing response…
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::parseState
Nov 03 12:20:32 volumio sudo[4603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Nov 03 12:20:32 volumio sudo[4603]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 03 12:20:32 volumio volumio[857]: info: Command Router : Notfying DB Updatefalse
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::Close All Modals sent
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::parseState
Nov 03 12:20:32 volumio volumio[857]: info: ControllerMpd::pushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::servicePushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::syncState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::pushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::getState
Nov 03 12:20:32 volumio sudo[4603]: pam_unix(sudo:session): session closed for user root
Nov 03 12:20:32 volumio volumio[857]: info: CorePlayQueue::getTrack 0
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::volumioPushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 03 12:20:32 volumio volumio[857]: info: CorePlayQueue::getTrack 0
Nov 03 12:20:32 volumio volumio[857]: 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}
Nov 03 12:20:32 volumio volumio[857]: info: CURRENT POSITION 0
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::syncState stateService stop
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::syncState currentStatus stop
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::pushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::getState
Nov 03 12:20:32 volumio volumio[857]: info: CorePlayQueue::getTrack 0
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::volumioPushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 03 12:20:32 volumio volumio[857]: info: No code
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::pushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreStateMachine::getState
Nov 03 12:20:32 volumio sudo[4615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Nov 03 12:20:32 volumio volumio[857]: info: CorePlayQueue::getTrack 0
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::volumioPushState
Nov 03 12:20:32 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 03 12:20:32 volumio sudo[4615]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 03 12:20:32 volumio sudo[4617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Nov 03 12:20:32 volumio sudo[4615]: pam_unix(sudo:session): session closed for user root
Nov 03 12:20:32 volumio sudo[4617]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 03 12:20:32 volumio sudo[4617]: pam_unix(sudo:session): session closed for user root
Nov 03 12:20:32 volumio volumio[857]: info: ------------------------------ 311ms
Nov 03 12:20:33 volumio sudo[4627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Nov 03 12:20:33 volumio sudo[4627]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 03 12:20:33 volumio sudo[4627]: pam_unix(sudo:session): session closed for user root
Nov 03 12:20:36 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:40 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:44 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:48 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:52 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:20:56 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:21:00 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:21:04 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:21:08 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:21:12 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:21:16 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 03 12:21:20 volumio volumio[857]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats