Volumio 2 does not scan a network share

Hi there,

I’m facing this problem with the last stable release of Volumio 2 on a Raspberry Pi B+: when I add a network mount and press Save the scanning process begins but it immediately fails with an error that is also shown in sudo journalctl -f which is:

Nov 22 12:33:38 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 22 12:33:38 volumio mpd[1308]: db: No database

So, I can’t see the share in the Browse tab, nor I can see any song counted in the “my music” page. What is going on?

Never saw that error!!! I suggest you to reflash…

I tried downloading it again and reflashing the rom but the results are exactly the same. As soon as I press Save it mounts the drive, it starts scanning but it soon stops with a Error popup in the right corner with kind of the same message that I posted above. Actually the “db:No database” error appears many times in the log, once every 2 seconds approximately.

Could it be that mpd does not have the permission to write the database to file? (Just guessing) What is wrong with it?

Hi,
I’ve got a siimilar problem : scan stop after 5 albums. I removed the share, re-add nothing happens. But sudo journalctl -f gives

Plugin: mpd , getMyCollectionStats Nov 22 20:21:13 volumio-bureau mpd[2328]: db: No database Nov 22 20:21:17 volumio-bureau volumio[3676]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 22 20:21:17 volumio-bureau mpd[2328]: db: No database Nov 22 20:21:21 volumio-bureau volumio[3676]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 22 20:21:21 volumio-bureau mpd[2328]: db: No database Nov 22 20:21:25 volumio-bureau volumio[3676]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 22 20:21:25 volumio-bureau mpd[2328]: db: No database

Hi,

I suffer the same issue with an Odroid C2 on version 2.002 and the actual 2.030

The scan starts and at approximately 300 albums the screen restarts to playback with nothing loaded. Everything is reset and the albums scanned so far are not stored.

Previously (I think the first version for the oidroid C2) and also my first attempts with a RPI3 (before version 2) worked fine.

The share are SMB connected through the web interface using cifs and credentials.

I tried with a fixed wired network address, a dhcp and a fixed DHCP (server assignment) and none worked.

Here is the code for the error

Nov 23 11:34:58 volumio mpd[1150]: update: added NAS/Musique/A -L/Bobby McFerrin/[1997] - Bobby McFerrin - Jazz Masters/07 - Bobby Mcferrin - I Hear Music.mp3
Nov 23 11:34:58 volumio mpd[1150]: update: added NAS/Musique/A -L/Bobby McFerrin/[1997] - Bobby McFerrin - Jazz Masters/08 - Bobby Mcferrin - Walkin'.mp3
Nov 23 11:34:58 volumio mpd[1150]: update: added NAS/Musique/A -L/Bobby McFerrin/[1997] - Bobby McFerrin - Jazz Masters/09 - Bobby Mcferrin - Round Midnight.mp3
Nov 23 11:34:58 volumio kernel: Unhandled fault: alignment fault (0x92000021) at 0x00000000eecce31d
Nov 23 11:34:58 volumio systemd[1]: mpd.service: main process exited, code=killed, status=7/BUS
Nov 23 11:34:58 volumio systemd[1]: Unit mpd.service entered failed state.
Nov 23 11:34:58 volumio systemd[1]: mpd.service holdoff time over, scheduling restart.
Nov 23 11:34:58 volumio systemd[1]: Stopping Music Player Daemon...
Nov 23 11:34:58 volumio systemd[1]: Starting Music Player Daemon...
Nov 23 11:34:58 volumio systemd[1]: Started Music Player Daemon.
Nov 23 11:34:59 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 23 11:34:59 volumio volumio[1168]: MPD error: Error: This socket has been ended by the other party
Nov 23 11:34:59 volumio volumio[1168]: MPD error: Error: This socket has been ended by the other party
Nov 23 11:34:59 volumio volumio[1168]: MPD error: Error: This socket has been ended by the other party
Nov 23 11:34:59 volumio mpd[1375]: zeroconf: No global port, disabling zeroconf
Nov 23 11:35:03 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 23 11:35:03 volumio volumio[1168]: MPD error: Error: This socket has been ended by the other party
Nov 23 11:35:03 volumio volumio[1168]: MPD error: Error: This socket has been ended by the other party
Nov 23 11:35:03 volumio volumio[1168]: MPD error: Error: This socket has been ended by the other party
Nov 23 11:35:07 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 23 11:35:07 volumio volumio[1168]: assert.js:85
Nov 23 11:35:07 volumio volumio[1168]: throw new assert.AssertionError({
Nov 23 11:35:07 volumio volumio[1168]: ^
Nov 23 11:35:07 volumio volumio[1168]: AssertionError: false == true
Nov 23 11:35:07 volumio volumio[1168]: at MpdClient.sendCommand (/volumio/node_modules/mpd/index.js:86:10)
Nov 23 11:35:07 volumio volumio[1168]: at ControllerMpd.getMyCollectionStats (/volumio/app/plugins/music_service/mpd/index.js:2256:20)
Nov 23 11:35:07 volumio volumio[1168]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:818:29)
Nov 23 11:35:07 volumio volumio[1168]: at Socket.<anonymous> (/volumio/app/plugins/user_interface/websocket/index.js:1112:43)
Nov 23 11:35:07 volumio volumio[1168]: at emitTwo (events.js:106:13)
Nov 23 11:35:07 volumio volumio[1168]: at Socket.emit (events.js:191:7)
Nov 23 11:35:07 volumio volumio[1168]: at Socket.onevent (/volumio/node_modules/socket.io/lib/socket.js:348:8)
Nov 23 11:35:07 volumio volumio[1168]: at Socket.onpacket (/volumio/node_modules/socket.io/lib/socket.js:308:12)
Nov 23 11:35:07 volumio volumio[1168]: at /volumio/node_modules/socket.io/lib/client.js:195:16
Nov 23 11:35:07 volumio volumio[1168]: at _combinedTickCallback (internal/process/next_tick.js:67:7)
Nov 23 11:35:07 volumio volumio[1168]: at process._tickCallback (internal/process/next_tick.js:98:9)
Nov 23 11:35:07 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE
Nov 23 11:35:07 volumio systemd[1]: Unit volumio.service entered failed state.
Nov 23 11:35:07 volumio systemd[1]: Starting dynamicswap.service...
Nov 23 11:35:07 volumio systemd[1]: Started dynamicswap.service.
Nov 23 11:35:07 volumio systemd[1]: volumio.service holdoff time over, scheduling restart.

I actually managed to solve my problem by doing the dumbest thing I did not try: restarting Volumio after the configuration and starting a rescan, it all worked fine. :confused: Have you tried doing so? However this is not a normal behaviour I think, could you investigate on its reason?

Hi,

For me the problem is still there, either for Network shares than for USB disks. As soon as there is more than around 200 artists the same issue occurs again and again.

That’s horrible

Hi again,

To add a bit of information my entire library is scanned properly with a RPI3 either on usb or from network share. It is really a problem located on other boards.

Lobo

Hi,

Is there any news on this bug ? Right now I can use my Odroid C2 volumio only with webradios or with a specific nas share containing only a few albums. There is no way I can scan my library. That’s make volumio quite useless :frowning:.

Many Thanks,

Lobo

To narrow down your problem we need more info.
How to you connect to your NAS, nfs or smb (cifs)?
Have you ssh’ed in before and tried “sudo journalctl -f” before starting scanning the library, following the log to the point where it fails?
-Gé-

Hi gkkpch,

I think the log is available some post ago in this thread, (post from Nov 23 2016 12:22) should you need something more I can provide it.

About my connection : SMB with cifs and also same issue with a connected hard drive in USB.

Have a nice day,

Lobo

OK, this seriously looks like an mpd issue, IIRC it was mentioned elsewhere on this forum or the Volumio issue list.

@lobotom1 could you disable ffmpeg in “/etc/mpd.conf” and run again, it won’t solve your problem, but I’m trying to eliminate ffmpeg misbehaviour

Hello,

I think I am doing it wrong.
I added at the end of /etc/mpd.conf the line :

decoder { plugin “ffmpeg” enabled “no” }

As soon as rebooted I can’t access to the menu “my music” and thus no way to add a network share or anything.

Strange, you added the correct parameters. I have used it to eliminate problems with .mp4 files in my folder where mpd thought they were audio…
Veeeeery strange as this should not have any relation with Volumio’s menu.
Out of ideas at the moment…
-Gé-

Hello again,

I tried on a fresh install, same behaviour with version 2.30.

Here is the log when clicking on “My music” from the volumio web interrface without the ffmpeg off parameter :

volumio@volumio:~$ sudo journalctl -f -- Logs begin at Sat 2017-01-21 12:23:20 UTC. -- Jan 21 15:09:36 volumio volumio[928]: info: CoreCommandRouter::volumioGetBrowseS ources Jan 21 15:09:36 volumio volumio[928]: info: CoreCommandRouter::volumioGetQueue Jan 21 15:09:36 volumio volumio[928]: info: [1485011376902] CoreStateMachine::ge tQueue Jan 21 15:09:36 volumio volumio[928]: info: [1485011376904] CorePlayQueue::getQu eue Jan 21 15:09:36 volumio volumio[928]: info: Jan 21 15:09:36 volumio volumio[928]: [1485011376906] -------------------------- -- Client requests Volumio queue Jan 21 15:09:36 volumio volumio[928]: info: [1485011376909] InterfaceWebUI::push Queue Jan 21 15:09:36 volumio volumio[928]: info: [1485011376912] -------------------- ---------- 12ms Jan 21 15:10:31 volumio sudo[1053]: volumio : TTY=pts/0 ; PWD=/home/volumio ; US ER=root ; COMMAND=/bin/journalctl -f Jan 21 15:10:31 volumio sudo[1053]: pam_unix(sudo:session): session opened for u ser root by volumio(uid=0) Jan 21 15:10:37 volumio volumio[928]: info: CoreCommandRouter::getUIConfigOnPlug in Jan 21 15:10:37 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 15:10:37 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 21 15:10:41 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 15:10:45 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 15:10:49 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 15:10:53 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 15:10:57 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 15:11:01 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 15:11:05 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 15:11:09 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats ^XJan 21 15:11:13 volumio volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats

Here is the same log with the parameter ffmpeg enabled “no” :

volumio@volumio:~$ sudo journalctl -f -- Logs begin at Sat 2017-01-21 15:12:36 UTC. -- Jan 21 15:14:37 volumio volumio[1055]: [1485011677113] ---------------------------- Client requests Volumio state Jan 21 15:14:37 volumio volumio[1055]: info: [1485011677114] InterfaceWebUI::pushState Jan 21 15:14:37 volumio volumio[1055]: info: [1485011677130] ------------------------------ 19ms Jan 21 15:14:42 volumio sudo[1039]: pam_unix(sudo:session): session closed for user root Jan 21 15:14:55 volumio volumio[1005]: src/mpdcli.cxx:127::MPDCli::showError: bad state Jan 21 15:14:55 volumio volumio[1005]: src/main.cxx:476::MPD connection failed Jan 21 15:15:59 volumio volumio[1005]: src/mpdcli.cxx:127::MPDCli::showError: bad state Jan 21 15:15:59 volumio volumio[1005]: src/main.cxx:476::MPD connection failed Jan 21 15:16:32 volumio sudo[1136]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Jan 21 15:16:32 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Jan 21 15:16:47 volumio volumio[1055]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 15:16:47 volumio volumio[1055]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 15:16:47 volumio volumio[1055]: /volumio/app/plugins/music_service/mpd/index.js:2256 Jan 21 15:16:47 volumio volumio[1055]: self.clientMpd.sendCommand(cmd("count", ["group", "artist"]), function (err, msg) { Jan 21 15:16:47 volumio volumio[1055]: ^ Jan 21 15:16:47 volumio volumio[1055]: TypeError: Cannot read property 'sendCommand' of undefined Jan 21 15:16:47 volumio volumio[1055]: at ControllerMpd.getMyCollectionStats (/volumio/app/plugins/music_service/mpd/index.js:2256:19) Jan 21 15:16:47 volumio volumio[1055]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:818:29) Jan 21 15:16:47 volumio volumio[1055]: at Socket.<anonymous> (/volumio/app/plugins/user_interface/websocket/index.js:1112:43) Jan 21 15:16:47 volumio volumio[1055]: at emitTwo (events.js:106:13) Jan 21 15:16:47 volumio volumio[1055]: at Socket.emit (events.js:191:7) Jan 21 15:16:47 volumio volumio[1055]: at Socket.onevent (/volumio/node_modules/socket.io/lib/socket.js:348:8) Jan 21 15:16:47 volumio volumio[1055]: at Socket.onpacket (/volumio/node_modules/socket.io/lib/socket.js:308:12) Jan 21 15:16:47 volumio volumio[1055]: at /volumio/node_modules/socket.io/lib/client.js:195:16 Jan 21 15:16:47 volumio volumio[1055]: at _combinedTickCallback (internal/process/next_tick.js:67:7) Jan 21 15:16:47 volumio volumio[1055]: at process._tickCallback (internal/process/next_tick.js:98:9) Jan 21 15:16:47 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Jan 21 15:16:47 volumio systemd[1]: Unit volumio.service entered failed state. Jan 21 15:16:47 volumio systemd[1]: Starting dynamicswap.service... Jan 21 15:16:47 volumio systemd[1]: Started dynamicswap.service. Jan 21 15:16:47 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Jan 21 15:16:47 volumio systemd[1]: Starting dynamicswap.service... Jan 21 15:16:47 volumio systemd[1]: Started dynamicswap.service. Jan 21 15:16:47 volumio systemd[1]: Stopping Volumio Backend Module... Jan 21 15:16:47 volumio systemd[1]: Starting Volumio Backend Module... Jan 21 15:16:47 volumio systemd[1]: Started Volumio Backend Module. Jan 21 15:16:49 volumio volumio[1153]: info: ------------------------------------------- Jan 21 15:16:49 volumio volumio[1153]: info: ----- Volumio2 ---- Jan 21 15:16:49 volumio volumio[1153]: info: ------------------------------------------- Jan 21 15:16:49 volumio volumio[1153]: info: ----- System startup ---- Jan 21 15:16:49 volumio volumio[1153]: info: ------------------------------------------- Jan 21 15:16:50 volumio volumio[1153]: info: Plugin folders cleanup Jan 21 15:16:50 volumio volumio[1153]: info: Scanning into folder /volumio/app/plugins/ Jan 21 15:16:50 volumio volumio[1153]: info: Scanning category audio_interface Jan 21 15:16:50 volumio volumio[1153]: info: Scanning category miscellanea Jan 21 15:16:50 volumio volumio[1153]: info: Scanning category music_service Jan 21 15:16:50 volumio volumio[1153]: info: Scanning category plugins.json Jan 21 15:16:50 volumio volumio[1153]: info: Scanning category system_controller Jan 21 15:16:50 volumio volumio[1153]: info: Scanning category user_interface Jan 21 15:16:50 volumio volumio[1153]: info: Scanning into folder /data/plugins/ Jan 21 15:16:50 volumio volumio[1153]: info: Plugin folders cleanup completed Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugins from folder /volumio/app/plugins/ Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugins from folder /data/plugins/ Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "system"... Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "appearance"... Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "network"... Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "services"... Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "alsa_controller"... Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "volumio_command_line_client"... Jan 21 15:16:50 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 15:16:50 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 15:16:50 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "upnp"... Jan 21 15:16:50 volumio volumio[1153]: info: [1485011810484] Starting Upmpd Daemon Jan 21 15:16:50 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "mpd"... Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "networkfs"... Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "alarm-clock"... Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "airplay_emulation"... Jan 21 15:16:50 volumio volumio[1153]: info: [1485011810672] Starting Shairport Sync Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "last_100"... Jan 21 15:16:50 volumio volumio[1153]: info: Loading plugin "webradio"... Jan 21 15:16:51 volumio volumio[1153]: info: Loading plugin "i2s_dacs"... Jan 21 15:16:51 volumio volumio[1153]: info: I2S DAC not set, start Auto-detection Jan 21 15:16:51 volumio volumio[1153]: info: Loading plugin "my_volumio"... Jan 21 15:16:51 volumio volumio[1153]: info: Loading plugin "volumiodiscovery"... Jan 21 15:16:51 volumio volumio[1153]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 15:16:51 volumio node[1153]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 15:16:51 volumio node[1153]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 15:16:51 volumio node[1153]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node> Jan 21 15:16:51 volumio node[1153]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 15:16:51 volumio node[1153]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 15:16:51 volumio node[1153]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister> Jan 21 15:16:51 volumio volumio[1153]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 15:16:51 volumio volumio[1153]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node> Jan 21 15:16:51 volumio volumio[1153]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 15:16:51 volumio volumio[1153]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 15:16:51 volumio volumio[1153]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister> Jan 21 15:16:51 volumio volumio[1153]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 21 15:16:51 volumio volumio[1153]: Discovery: StartAdv! undefined Jan 21 15:16:51 volumio volumio[1153]: Discovery: Started advertising... Volumio - undefined Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 15:16:51 volumio volumio[1153]: info: Loading plugin "albumart"... Jan 21 15:16:51 volumio volumio[1153]: info: Plugin example_plugin is not enabled Jan 21 15:16:51 volumio volumio[1153]: info: Loading plugin "updater_comm"... Jan 21 15:16:51 volumio volumio[1153]: info: Plugin mpdemulation is not enabled Jan 21 15:16:51 volumio volumio[1153]: info: Loading plugin "websocket"... Jan 21 15:16:51 volumio volumio[1153]: info: ___________ START PLUGINS ___________ Jan 21 15:16:51 volumio volumio[1153]: PLUGIN START: appearance Jan 21 15:16:51 volumio volumio[1153]: PLUGIN START: last_100 Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 15:16:51 volumio volumio[1153]: info: [1485011811682] CoreMusicLibrary::Adding element Last_100 Jan 21 15:16:51 volumio volumio[1153]: PLUGIN START: webradio Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 15:16:51 volumio volumio[1153]: info: [1485011811693] CoreMusicLibrary::Adding element Webradio Jan 21 15:16:51 volumio volumio[1153]: PLUGIN START: my_volumio Jan 21 15:16:51 volumio volumio[1153]: info: Loading i18n strings for locale en Jan 21 15:16:51 volumio volumio[1153]: Updating browse sources language Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 15:16:51 volumio volumio[1153]: info: BOOT COMPLETED Jan 21 15:16:51 volumio volumio[1153]: Express server listening on port 3000 Jan 21 15:16:51 volumio volumio[1153]: Volumio Calling Home Jan 21 15:16:51 volumio volumio[1153]: info: [1485011811841] CoreStateMachine::resetVolumioState Jan 21 15:16:51 volumio volumio[1153]: info: [1485011811842] CoreStateMachine::getcurrentVolume Jan 21 15:16:51 volumio volumio[1153]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 15:16:51 volumio volumio[1153]: info: [1485011811864] CoreStateMachine::updateTrackBlock Jan 21 15:16:51 volumio volumio[1153]: info: [1485011811865] CorePlayQueue::getTrackBlock Jan 21 15:16:51 volumio volumio[1153]: info: Cannot read play queue form file Jan 21 15:16:51 volumio volumio[1153]: { Error: Command failed: /usr/bin/aplay --device=plughw:undefined,0 /volumio/app/startup.wav Jan 21 15:16:51 volumio volumio[1153]: ALSA lib pcm_hw.c:1667:(_snd_pcm_hw_open) Invalid value for card Jan 21 15:16:51 volumio volumio[1153]: aplay: main:722: audio open error: No such device Jan 21 15:16:51 volumio volumio[1153]: at ChildProcess.exithandler (child_process.js:206:12) Jan 21 15:16:52 volumio volumio[1153]: at emitTwo (events.js:106:13) Jan 21 15:16:52 volumio volumio[1153]: at ChildProcess.emit (events.js:191:7) Jan 21 15:16:52 volumio volumio[1153]: at maybeClose (internal/child_process.js:877:16) Jan 21 15:16:52 volumio volumio[1153]: at Socket.<anonymous> (internal/child_process.js:334:11) Jan 21 15:16:52 volumio volumio[1153]: at emitOne (events.js:96:13) Jan 21 15:16:52 volumio volumio[1153]: at Socket.emit (events.js:188:7) Jan 21 15:16:52 volumio volumio[1153]: at Pipe._handle.close [as _onclose] (net.js:498:12) Jan 21 15:16:52 volumio volumio[1153]: killed: false, Jan 21 15:16:52 volumio volumio[1153]: code: 1, Jan 21 15:16:52 volumio volumio[1153]: signal: null, Jan 21 15:16:52 volumio volumio[1153]: cmd: '/usr/bin/aplay --device=plughw:undefined,0 /volumio/app/startup.wav' } Jan 21 15:16:52 volumio sudo[1212]: pam_unix(sudo:auth): conversation failed Jan 21 15:16:52 volumio sudo[1212]: pam_unix(sudo:auth): auth could not identify password for [volumio] Jan 21 15:16:52 volumio volumio[1153]: info: Cannot initialize MPD Connection: MPD is not running Jan 21 15:16:52 volumio volumio[1153]: info: Setting Device type: Odroid-C2 Jan 21 15:16:52 volumio volumio[1153]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jan 21 15:16:52 volumio volumio[1153]: sudo: no tty present and no askpass program specified Jan 21 15:16:52 volumio volumio[1153]: null Jan 21 15:16:52 volumio volumio[1153]: info: [1485011812331] VolumeController:: Volume=undefined Mute =undefined Jan 21 15:16:52 volumio volumio[1153]: info: [1485011812335] CoreStateMachine::pushState Jan 21 15:16:52 volumio volumio[1153]: info: [1485011812338] CoreStateMachine::getState Jan 21 15:16:52 volumio volumio[1153]: info: [1485011812339] CorePlayQueue::getTrack 0 Jan 21 15:16:52 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState Jan 21 15:16:52 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 15:16:52 volumio volumio[1153]: info: [1485011812346] InterfaceWebUI::pushState Jan 21 15:16:52 volumio volumio[1153]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} Jan 21 15:16:52 volumio sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 21 15:16:52 volumio sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 15:16:52 volumio sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Jan 21 15:16:52 volumio sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 15:16:52 volumio volumio[1153]: info: Volumio called home Jan 21 15:16:52 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 21 15:16:52 volumio sudo[1217]: pam_unix(sudo:session): session closed for user root Jan 21 15:16:52 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Jan 21 15:16:52 volumio shairport-sync[1124]: exit... Jan 21 15:16:52 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Jan 21 15:16:52 volumio sudo[1218]: pam_unix(sudo:session): session closed for user root Jan 21 15:16:52 volumio systemd[1]: Started ShairportSync AirTunes receiver. Jan 21 15:16:52 volumio shairport-sync[1222]: startup Jan 21 15:16:52 volumio volumio[1153]: info: [1485011812571] Shairport-Sync Started Jan 21 15:16:52 volumio volumio[1153]: info: Upmpdcli Daemon Started Jan 21 15:16:52 volumio volumio[1153]: Discovery: adding 5b30b4c9-7440-4c1d-a179-e1d4d664436a Jan 21 15:16:52 volumio volumio[1153]: info: mDNS: Found device Volumio Jan 21 15:16:52 volumio volumio[1153]: success! Jan 21 15:16:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 21 15:16:54 volumio volumio[1153]: info: CoreCommandRouter::volumioGetState Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814757] CoreStateMachine::getState Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814758] CorePlayQueue::getTrack 0 Jan 21 15:16:54 volumio volumio[1153]: info: Jan 21 15:16:54 volumio volumio[1153]: [1485011814759] ---------------------------- Client requests Volumio state Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814762] Listing playlists Jan 21 15:16:54 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 15:16:54 volumio volumio[1153]: Plugin multiroom or method getMultiroom not found Jan 21 15:16:54 volumio volumio[1153]: info: Jan 21 15:16:54 volumio volumio[1153]: [1485011814780] ---------------------------- Client requests Menu Items Jan 21 15:16:54 volumio volumio[1153]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 15:16:54 volumio volumio[1153]: info: CoreCommandRouter::volumioGetQueue Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814785] CoreStateMachine::getQueue Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814787] CorePlayQueue::getQueue Jan 21 15:16:54 volumio volumio[1153]: info: Jan 21 15:16:54 volumio volumio[1153]: [1485011814788] ---------------------------- Client requests Volumio queue Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814789] InterfaceWebUI::pushState Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814809] InterfaceWebUI::pushQueue Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814813] ------------------------------ 57ms Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814815] ------------------------------ 31ms Jan 21 15:16:54 volumio volumio[1153]: info: [1485011814890] ------------------------------ 111ms Jan 21 15:16:55 volumio volumio[1153]: info: CoreCommandRouter::volumioGetState Jan 21 15:16:55 volumio volumio[1153]: info: [1485011815003] CoreStateMachine::getState Jan 21 15:16:55 volumio volumio[1153]: info: [1485011815003] CorePlayQueue::getTrack 0 Jan 21 15:16:55 volumio volumio[1153]: info: Jan 21 15:16:55 volumio volumio[1153]: [1485011815004] ---------------------------- Client requests Volumio state Jan 21 15:16:55 volumio volumio[1153]: info: [1485011815006] InterfaceWebUI::pushState Jan 21 15:16:55 volumio volumio[1153]: info: [1485011815020] ------------------------------ 19ms

It is like a full restart of the web UI.

I am seeing the same issue.
Fresh install of the latest Volumio2
Export all my music via NFS
Volumio reports share is mounted, shows the correct share size and df reveals:
192.168.0.188:/home/music/nfs 232G 39G 182G 18% /mnt/NAS/MUSICSHARE
and I can see the mp3 files in the mount at the Unix command line and read them
but
Artists/Albums/Tracks stay resolutely at zero, no matter how many “Rescans” I request

Update: I switched from NFS to CIFS using Samba server and now it is working, I did have to open up permissions on the directories on the server, which for some reason had been set to strictly.

I am having the exact same problem. Share is mounted at /mnt/NAS/Musik and all the files are accessible, still nothing shows up. Reinstalled the latest version yesterday no luck…

I’m still having problem having my whole NAS (cifs) mount scanned.
Procedure stops at the exact same point where the previous scan had, so only a part of my collection being available-playable.

I’m running the latest Volumio onto a freshly flashed image (Raspberry Pi 2) and also did an sudo apt-get update & apt-get upgrade but nothing changed.

I run a

sudo journalctl -f

which returns

volumio@volumio:~$ sudo sudo journalctl -f -- Logs begin at Fri 2017-02-17 14:45:24 UTC. -- Feb 17 16:12:46 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:12:50 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:12:54 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:12:58 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:02 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:06 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:06 volumio sudo[2792]: volumio : TTY=pts/0 ; PWD=/home/volumio ; US ER=root ; COMMAND=/usr/bin/sudo journalctl -f Feb 17 16:13:06 volumio sudo[2792]: pam_unix(sudo:session): session opened for u ser root by volumio(uid=0) Feb 17 16:13:06 volumio sudo[2796]: root : TTY=pts/0 ; PWD=/home/volumio ; USER= root ; COMMAND=/bin/journalctl -f Feb 17 16:13:06 volumio sudo[2796]: pam_unix(sudo:session): session opened for u ser root by volumio(uid=0) Feb 17 16:13:10 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:14 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:18 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:22 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:26 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:30 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:34 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:38 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:42 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:46 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:50 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:54 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats -- Logs begin at Fri 2017-02-17 14:45:24 UTC. -- Feb 17 16:12:46 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:12:50 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:12:54 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:12:58 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:02 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:06 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:06 volumio sudo[2792]: volumio : TTY=pts/0 ; PWD=/home/volumio ; US ER=root ; COMMAND=/usr/bin/sudo journalctl -f Feb 17 16:13:06 volumio sudo[2792]: pam_unix(sudo:session): session opened for u ser root by volumio(uid=0) Feb 17 16:13:06 volumio sudo[2796]: root : TTY=pts/0 ; PWD=/home/volumio ; USER= root ; COMMAND=/bin/journalctl -f Feb 17 16:13:06 volumio sudo[2796]: pam_unix(sudo:session): session opened for u ser root by volumio(uid=0) Feb 17 16:13:10 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:14 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:18 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:22 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:26 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:30 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:34 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:38 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:42 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:13:46 volumio volumio[814]: info: CoreCFeb 17 16:13:58 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:02 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:06 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:10 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:14 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:18 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:22 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:27 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:30 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:34 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:38 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:42 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:46 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:50 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:54 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:14:58 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:02 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:06 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:10 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:14 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:19 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:22 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:26 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:30 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:34 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:38 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:42 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 17 16:15:46 volumio volumio[814]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats

Any ideas?

Hello,

I do have the same problem. I’m using Volumio v. 2.040 for Odroid C2. I have an NAS attached via NFS. In a prior setup the harddrive was connected directly to the C2. The difference in scanning the library is, that while connected directly, the scan is finished at around 200 artists albeit incomplete. It justs stops and can’t add more.

Unfortunately I don’t have any logs.

Mounted via network the result is a restart of mpd.
See attached log:

[code]Mar 05 19:56:23 volumio mpd[600]: update: added NAS/Musik/blabla.mp3
Mar 05 19:56:23 volumio kernel: Unhandled fault: alignment fault (0x92000021) at 0x00000000ee480c0e
Mar 05 19:56:23 volumio systemd[1]: mpd.service: main process exited, code=killed, status=7/BUS
Mar 05 19:56:23 volumio systemd[1]: Unit mpd.service entered failed state.
Mar 05 19:56:23 volumio systemd[1]: mpd.service holdoff time over, scheduling restart.
Mar 05 19:56:23 volumio systemd[1]: Stopping Music Player Daemon…
Mar 05 19:56:23 volumio systemd[1]: Starting Music Player Daemon…
Mar 05 19:56:23 volumio systemd[1]: Started Music Player Daemon.
Mar 05 19:56:24 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 05 19:56:24 volumio volumio[886]: MPD error: Error: This socket has been ended by the other party
Mar 05 19:56:24 volumio volumio[886]: MPD error: Error: This socket has been ended by the other party
Mar 05 19:56:24 volumio volumio[886]: MPD error: Error: This socket has been ended by the other party
Mar 05 19:56:24 volumio mpd[1672]: zeroconf: No global port, disabling zeroconf
Mar 05 19:56:28 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 05 19:56:28 volumio volumio[886]: MPD error: Error: This socket has been ended by the other party
Mar 05 19:56:28 volumio volumio[886]: MPD error: Error: This socket has been ended by the other party
Mar 05 19:56:28 volumio volumio[886]: MPD error: Error: This socket has been ended by the other party

Mar 05 19:56:32 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 05 19:56:32 volumio volumio[886]: assert.js:85
Mar 05 19:56:32 volumio volumio[886]: throw new assert.AssertionError({
Mar 05 19:56:32 volumio volumio[886]: ^
Mar 05 19:56:32 volumio volumio[886]: AssertionError: false == true
Mar 05 19:56:32 volumio volumio[886]: at MpdClient.sendCommand (/volumio/node_modules/mpd/index.js:86:10)
Mar 05 19:56:32 volumio volumio[886]: at ControllerMpd.getMyCollectionStats (/volumio/app/plugins/music_service/mpd/index.js:2256:20)
Mar 05 19:56:32 volumio volumio[886]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:931:29)
Mar 05 19:56:32 volumio volumio[886]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1122:43)
Mar 05 19:56:32 volumio volumio[886]: at emitTwo (events.js:106:13)
Mar 05 19:56:32 volumio volumio[886]: at Socket.emit (events.js:191:7)
Mar 05 19:56:32 volumio volumio[886]: at Socket.onevent (/volumio/node_modules/socket.io/lib/socket.js:348:8)
Mar 05 19:56:32 volumio volumio[886]: at Socket.onpacket (/volumio/node_modules/socket.io/lib/socket.js:308:12)
Mar 05 19:56:32 volumio volumio[886]: at /volumio/node_modules/socket.io/lib/client.js:195:16
Mar 05 19:56:32 volumio volumio[886]: at _combinedTickCallback (internal/process/next_tick.js:67:7)
Mar 05 19:56:32 volumio volumio[886]: at process._tickCallback (internal/process/next_tick.js:98:9)
Mar 05 19:56:32 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE
Mar 05 19:56:32 volumio systemd[1]: Unit volumio.service entered failed state.
Mar 05 19:56:32 volumio systemd[1]: Starting dynamicswap.service…
Mar 05 19:56:32 volumio systemd[1]: Started dynamicswap.service.
Mar 05 19:56:32 volumio systemd[1]: volumio.service holdoff time over, scheduling restart.
Mar 05 19:56:32 volumio systemd[1]: Starting dynamicswap.service…
Mar 05 19:56:32 volumio systemd[1]: Started dynamicswap.service.
Mar 05 19:56:32 volumio systemd[1]: Stopping Volumio Backend Module…
Mar 05 19:56:32 volumio systemd[1]: Starting Volumio Backend Module…
Mar 05 19:56:32 volumio systemd[1]: Started Volumio Backend Module.
Mar 05 19:56:35 volumio volumio[1691]: info: -------------------------------------------
Mar 05 19:56:35 volumio volumio[1691]: info: ----- Volumio2 ----
Mar 05 19:56:35 volumio volumio[1691]: info: -------------------------------------------
Mar 05 19:56:35 volumio volumio[1691]: info: ----- System startup ----
Mar 05 19:56:35 volumio volumio[1691]: info: -------------------------------------------
Mar 05 19:56:35 volumio volumio[1691]: info: Plugin folders cleanup
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning into folder /volumio/app/plugins/
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning category audio_interface
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning category miscellanea
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning category music_service
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning category plugins.json
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning category system_controller
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning category user_interface
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning into folder /data/plugins/
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning category miscellanea
Mar 05 19:56:35 volumio volumio[1691]: info: Scanning category music_service
Mar 05 19:56:35 volumio volumio[1691]: info: Plugin folders cleanup completed
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugins from folder /volumio/app/plugins/
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugins from folder /data/plugins/
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “system”…
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “appearance”…
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “network”…
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “services”…
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “alsa_controller”…
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “volumio_command_line_client”…
Mar 05 19:56:35 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 19:56:35 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 19:56:35 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “upnp”…
Mar 05 19:56:35 volumio volumio[1691]: info: [1488743795811] Starting Upmpd Daemon
Mar 05 19:56:35 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “mpd”…
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “networkfs”…
Mar 05 19:56:35 volumio volumio[1691]: Something is already mounted on /mnt/NAS/Musik
Mar 05 19:56:35 volumio volumio[1691]: info: Cannot mount NAS Musik at system boot, trial number 1 ,retrying in 5 seconds
Mar 05 19:56:35 volumio volumio[1691]: info: Loading plugin “alarm-clock”…
Mar 05 19:56:36 volumio volumio[1691]: info: Loading plugin “airplay_emulation”…
Mar 05 19:56:36 volumio volumio[1691]: info: [1488743796077] Starting Shairport Sync
Mar 05 19:56:36 volumio volumio[1691]: info: Loading plugin “last_100”…
Mar 05 19:56:36 volumio volumio[1691]: info: Loading plugin “webradio”…
Mar 05 19:56:36 volumio volumio[1691]: info: Loading plugin “i2s_dacs”…
Mar 05 19:56:36 volumio volumio[1691]: info: I2S DAC not set, start Auto-detection
Mar 05 19:56:36 volumio volumio[1691]: info: Loading plugin “my_volumio”…
Mar 05 19:56:36 volumio volumio[1691]: info: Loading plugin “volumiodiscovery”…
Mar 05 19:56:37 volumio volumio[1691]: *** WARNING *** The program ‘node’ uses the Apple Bonjour compatibility layer of Avahi.
Mar 05 19:56:37 volumio node[1691]: *** WARNING *** The program ‘node’ uses the Apple Bonjour compatibility layer of Avahi.
Mar 05 19:56:37 volumio node[1691]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 05 19:56:37 volumio node[1691]: *** WARNING *** For more information see http://0pointer.de/avahi-compat?s=libdns_sd&e=node
Mar 05 19:56:37 volumio node[1691]: *** WARNING *** The program ‘node’ called ‘DNSServiceRegister()’ which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Mar 05 19:56:37 volumio node[1691]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 05 19:56:37 volumio node[1691]: *** WARNING *** For more information see http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister
Mar 05 19:56:37 volumio volumio[1691]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 05 19:56:37 volumio volumio[1691]: *** WARNING *** For more information see http://0pointer.de/avahi-compat?s=libdns_sd&e=node
Mar 05 19:56:37 volumio volumio[1691]: *** WARNING *** The program ‘node’ called ‘DNSServiceRegister()’ which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Mar 05 19:56:37 volumio volumio[1691]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 05 19:56:37 volumio volumio[1691]: *** WARNING *** For more information see http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister
Mar 05 19:56:37 volumio volumio[1691]: info: Applying required configuration parameters for plugin volumiodiscovery
Mar 05 19:56:37 volumio volumio[1691]: Discovery: StartAdv! undefined
Mar 05 19:56:37 volumio volumio[1691]: Discovery: Started advertising… Volumio - undefined
Mar 05 19:56:37 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 05 19:56:37 volumio volumio[1691]: info: Loading plugin “albumart”…
Mar 05 19:56:37 volumio volumio[1691]: info: Plugin example_plugin is not enabled
Mar 05 19:56:37 volumio volumio[1691]: info: Loading plugin “updater_comm”…
Mar 05 19:56:37 volumio volumio[1691]: info: Plugin mpdemulation is not enabled
Mar 05 19:56:37 volumio volumio[1691]: info: Loading plugin “rest_api”…
Mar 05 19:56:37 volumio volumio[1691]: info: Loading plugin “websocket”…
Mar 05 19:56:37 volumio volumio[1691]: info: Loading plugin “touch_display”…
Mar 05 19:56:37 volumio volumio[1691]: info: Loading plugin “spop”…
Mar 05 19:56:38 volumio volumio[1691]: info: Applying required configuration parameters for plugin spop
Mar 05 19:56:38 volumio volumio[1691]: info: ___________ START PLUGINS ___________
Mar 05 19:56:38 volumio volumio[1691]: PLUGIN START: appearance
Mar 05 19:56:38 volumio volumio[1691]: PLUGIN START: last_100
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 05 19:56:38 volumio volumio[1691]: info: [1488743798379] CoreMusicLibrary::Adding element Last_100
Mar 05 19:56:38 volumio volumio[1691]: PLUGIN START: webradio
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 05 19:56:38 volumio volumio[1691]: info: [1488743798398] CoreMusicLibrary::Adding element Webradio
Mar 05 19:56:38 volumio volumio[1691]: PLUGIN START: my_volumio
Mar 05 19:56:38 volumio volumio[1691]: PLUGIN START: touch_display
Mar 05 19:56:38 volumio volumio[1691]: PLUGIN START: spop
Mar 05 19:56:38 volumio volumio[1691]: info: Loading i18n strings for locale en
Mar 05 19:56:38 volumio volumio[1691]: Updating browse sources language
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards
Mar 05 19:56:38 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Mar 05 19:56:38 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 05 19:56:38 volumio sudo[1746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service
Mar 05 19:56:38 volumio sudo[1746]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 19:56:38 volumio systemd[1]: Started Start Volumio Kiosk.
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 19:56:38 volumio systemd[1]: Starting Spotify Daemon…
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 19:56:38 volumio systemd[1]: Started Spotify Daemon.
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 19:56:38 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root
Mar 05 19:56:38 volumio sudo[1746]: pam_unix(sudo:session): session closed for user root
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 05 19:56:38 volumio volumio[1691]: info: BOOT COMPLETED
Mar 05 19:56:38 volumio volumio[1691]: Express server listening on port 3000
Mar 05 19:56:38 volumio volumio[1691]: Volumio Calling Home
Mar 05 19:56:38 volumio volumio[1749]: 2017-03-05 19:56:38 [SPTF] 19:56:38.658 I [offline_authorizer.cpp:297] Unable to login offline: no such user
Mar 05 19:56:38 volumio volumio[1749]: 2017-03-05 19:56:38 [SPTF] 19:56:38.677 I [ap:1752] Connecting to AP ap.spotify.com:4070
Mar 05 19:56:38 volumio volumio[1691]: info: [1488743798713] CoreStateMachine::resetVolumioState
Mar 05 19:56:38 volumio volumio[1691]: info: [1488743798726] CoreStateMachine::getcurrentVolume
Mar 05 19:56:38 volumio volumio[1691]: info: CoreCommandRouter::volumioRetrievevolume
Mar 05 19:56:38 volumio kernel: [aml-i2s-dai]i2s dma ffffff80009e1000,phy addr 1612447744,mode 0,ch 2
Mar 05 19:56:38 volumio kernel: ----aml_hw_iec958_init,runtime->rate=44100,sample_rate=5–
Mar 05 19:56:38 volumio volumio[1691]: info: [1488743798769] CoreStateMachine::updateTrackBlock
Mar 05 19:56:38 volumio volumio[1691]: info: [1488743798773] CorePlayQueue::getTrackBlock
Mar 05 19:56:38 volumio kernel: aml_set_spdif_clk rate
Mar 05 19:56:38 volumio kernel: divider=22,frac=13030400,SDMval=2364
Mar 05 19:56:38 volumio kernel: set normal 512 fs /4 fs
Mar 05 19:56:38 volumio kernel: iec958 mode PCM16
Mar 05 19:56:38 volumio kernel: IEC958 16bit
Mar 05 19:56:38 volumio kernel: hdmitx: audio: aout notify rate 44100
Mar 05 19:56:38 volumio kernel: hdmitx: audio: aout notify size 16
Mar 05 19:56:38 volumio kernel: 958 with i2s
Mar 05 19:56:38 volumio kernel: aiu i2s playback enable
Mar 05 19:56:38 volumio volumio[1691]: info: Cannot read play queue form file
Mar 05 19:56:38 volumio volumio[1691]: info: SpopD Daemon Started
Mar 05 19:56:38 volumio volumio[1691]: info: Volumio Kiosk Started
Mar 05 19:56:38 volumio volumio[1691]: info: Kiosk Started
Mar 05 19:56:39 volumio volumio[1691]: info: MPD running with PID1672 ,establishing connection
Mar 05 19:56:39 volumio volumio[1691]: info: Setting Device type: Odroid-C2
Mar 05 19:56:39 volumio sudo[1760]: pam_unix(sudo:auth): conversation failed
Mar 05 19:56:39 volumio sudo[1760]: pam_unix(sudo:auth): auth could not identify password for [volumio]
Mar 05 19:56:39 volumio volumio[1749]: 2017-03-05 19:56:39 [SPTF] 19:56:39.155 I [ap:1226] Connected to AP: 194.132.168.66:4070
Mar 05 19:56:39 volumio volumio[1749]: 2017-03-05 19:56:39 [SPTF] 19:56:39.301 E [ap:3953] Connection error: 409
Mar 05 19:56:39 volumio volumio[1749]: 2017-03-05 19:56:39 [WARN] Login failed: User needs a premium account
Mar 05 19:56:39 volumio volumio[1749]: 2017-03-05 19:56:39 [ERR ] Could not get the playlist container.
Mar 05 19:56:39 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP
Mar 05 19:56:39 volumio systemd[1]: Unit spop.service entered failed state.
Mar 05 19:56:39 volumio volumio[1691]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1
Mar 05 19:56:39 volumio volumio[1691]: sudo: no tty present and no askpass program specified
Mar 05 19:56:39 volumio volumio[1691]: info: [1488743799416] VolumeController:: Volume=undefined Mute =undefined
Mar 05 19:56:39 volumio volumio[1691]: info: [1488743799424] CoreStateMachine::pushState
Mar 05 19:56:39 volumio volumio[1691]: info: [1488743799427] CoreStateMachine::getState
Mar 05 19:56:39 volumio volumio[1691]: info: [1488743799429] CorePlayQueue::getTrack 0
Mar 05 19:56:39 volumio volumio[1691]: info: CoreCommandRouter::volumioPushState
Mar 05 19:56:39 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 05 19:56:39 volumio volumio[1691]: info: [1488743799445] interfaceApi::pushState
Mar 05 19:56:39 volumio volumio[1691]: info: [1488743799447] InterfaceWebUI::pushState
Mar 05 19:56:39 volumio volumio[1691]: info: Pushing Favourites {“service”:“mpd”,“uri”:"",“favourite”:false}
Mar 05 19:56:39 volumio volumio[1691]: info: Volumio called home
Mar 05 19:56:39 volumio sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Mar 05 19:56:39 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 05 19:56:39 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Mar 05 19:56:39 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root
Mar 05 19:56:39 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Mar 05 19:56:39 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 05 19:56:39 volumio volumio[1691]: Discovery: adding ae830ff2-c294-4d5b-bd44-abf05aad955c
Mar 05 19:56:39 volumio volumio[1691]: info: mDNS: Found device Volumio
Mar 05 19:56:39 volumio systemd[1]: Stopping ShairportSync AirTunes receiver…
Mar 05 19:56:39 volumio shairport-sync[1111]: exit…
Mar 05 19:56:39 volumio systemd[1]: Starting ShairportSync AirTunes receiver…
Mar 05 19:56:39 volumio systemd[1]: Started ShairportSync AirTunes receiver.
Mar 05 19:56:39 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root
Mar 05 19:56:39 volumio shairport-sync[1773]: startup
Mar 05 19:56:39 volumio volumio[1691]: info: Upmpdcli Daemon Started
Mar 05 19:56:39 volumio volumio[1691]: info: [1488743799648] Shairport-Sync Started
Mar 05 19:56:39 volumio volumio[1691]: success!
Mar 05 19:56:40 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Mar 05 19:56:40 volumio volumio[1691]: info: CoreCommandRouter::volumioGetState
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800375] CoreStateMachine::getState
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800376] CorePlayQueue::getTrack 0
Mar 05 19:56:40 volumio volumio[1691]: info:
Mar 05 19:56:40 volumio volumio[1691]: [1488743800378] ---------------------------- Client requests Volumio state
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800383] Listing playlists
Mar 05 19:56:40 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 05 19:56:40 volumio volumio[1691]: Plugin multiroom or method getMultiroom not found
Mar 05 19:56:40 volumio volumio[1691]: info:
Mar 05 19:56:40 volumio volumio[1691]: [1488743800407] ---------------------------- Client requests Menu Items
Mar 05 19:56:40 volumio volumio[1691]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 05 19:56:40 volumio volumio[1691]: info: CoreCommandRouter::volumioGetQueue
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800415] CoreStateMachine::getQueue
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800416] CorePlayQueue::getQueue
Mar 05 19:56:40 volumio volumio[1691]: info:
Mar 05 19:56:40 volumio volumio[1691]: [1488743800418] ---------------------------- Client requests Volumio queue
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800420] InterfaceWebUI::pushState
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800441] InterfaceWebUI::pushQueue
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800444] ------------------------------ 71ms
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800446] ------------------------------ 33ms
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800517] ------------------------------ 110ms
Mar 05 19:56:40 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Mar 05 19:56:40 volumio volumio[1691]: info: CoreCommandRouter::volumioGetState
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800625] CoreStateMachine::getState
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800626] CorePlayQueue::getTrack 0
Mar 05 19:56:40 volumio volumio[1691]: info:
Mar 05 19:56:40 volumio volumio[1691]: [1488743800627] ---------------------------- Client requests Volumio state
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800629] InterfaceWebUI::pushState
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800651] ------------------------------ 27ms
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800661] Listing playlists
Mar 05 19:56:40 volumio volumio[1691]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 05 19:56:40 volumio volumio[1691]: Plugin multiroom or method getMultiroom not found
Mar 05 19:56:40 volumio volumio[1691]: info:
Mar 05 19:56:40 volumio volumio[1691]: [1488743800691] ---------------------------- Client requests Menu Items
Mar 05 19:56:40 volumio volumio[1691]: info: [1488743800704] ------------------------------ 13ms
Mar 05 19:56:40 volumio volumio[1691]: Something is already mounted on /mnt/NAS/Musik
Mar 05 19:56:40 volumio volumio[1691]: info: Cannot mount NAS Musik at system boot, trial number 2 ,retrying in 5 seconds
Mar 05 19:56:41 volumio volumio[1691]: info: CoreCommandRouter::volumioGetState
Mar 05 19:56:41 volumio volumio[1691]: info: [1488743801581] CoreStateMachine::getState
Mar 05 19:56:41 volumio volumio[1691]: info: [1488743801582] CorePlayQueue::getTrack 0
Mar 05 19:56:41 volumio volumio[1691]: info:
Mar 05 19:56:41 volumio volumio[1691]: [1488743801584] ---------------------------- Client requests Volumio state
Mar 05 19:56:41 volumio volumio[1691]: info: [1488743801588] InterfaceWebUI::pushState
Mar 05 19:56:41 volumio volumio[1691]: info: [1488743801604] ------------------------------ 25ms
Mar 05 19:56:43 volumio volumio[1691]: info: Connecting to daemon
Mar 05 19:56:44 volumio kernel: aiu i2s playback disable
Mar 05 19:56:44 volumio kernel: audio_hw_958_enable 0
Mar 05 19:56:44 volumio volumio[1691]: info: SPOP command error:
Mar 05 19:56:44 volumio volumio[1691]: info: Error: connect ECONNREFUSED 127.0.0.1:6602
Mar 05 19:56:44 volumio volumio[1691]: at Object.exports._errnoException (util.js:1026:11)
Mar 05 19:56:44 volumio volumio[1691]: at exports._exceptionWithHostPort (util.js:1049:20)
Mar 05 19:56:44 volumio volumio[1691]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1085:14)
Mar 05 19:56:44 volumio volumio[1691]: info: SPOP status error:
Mar 05 19:56:44 volumio volumio[1691]: info: Error: connect ECONNREFUSED 127.0.0.1:6602
Mar 05 19:56:44 volumio volumio[1691]: at Object.exports._errnoException (util.js:1026:11)
Mar 05 19:56:44 volumio volumio[1691]: at exports._exceptionWithHostPort (util.js:1049:20)
Mar 05 19:56:44 volumio volumio[1691]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1085:14)
Mar 05 19:56:44 volumio volumio[1691]: info: Spotify access token expires at 1488747403986
Mar 05 19:56:44 volumio volumio[1691]: info: Spotify access token is BQCESK-g8zcwwvu8Nc_uW_RUUUp-BBKk4xekZ2UI2iPa9xfo09AxPieHWSqfcpAZaylrZJ9MLZOz9sqT23jKFg
Mar 05 19:56:44 volumio volumio[1691]: info: Spotify credentials grant success
Mar 05 19:56:46 volumio volumio[1691]: Something is already mounted on /mnt/NAS/Musik
Mar 05 19:56:46 volumio volumio[1691]: info: Cannot mount NAS Musik at system boot, trial number 3 ,retrying in 5 seconds
Mar 05 19:56:51 volumio volumio[1691]: Something is already mounted on /mnt/NAS/Musik
Mar 05 19:56:51 volumio volumio[1691]: info: Cannot mount NAS Musik at system boot, trial number 4 ,retrying in 5 seconds
Mar 05 19:56:54 volumio volumio[1109]: src/mpdcli.cxx:137::MPDCli::openconn failed: Timeout
Mar 05 19:56:54 volumio volumio[1109]: src/mpdcli.cxx:181::MPDCli::updStatus: can’t get status
Mar 05 19:56:54 volumio volumio[1109]: src/mpdcli.cxx:137::MPDCli::updStatus failed: Timeout
Mar 05 19:56:56 volumio volumio[1691]: Something is already mounted on /mnt/NAS/Musik
Mar 05 19:56:56 volumio volumio[1691]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Mar 05 19:57:23 volumio mpd[1672]: client: [0] opened from ::ffff:127.0.0.1:49736
Mar 05 19:57:23 volumio mpd[1672]: client: [1] opened from ::ffff:127.0.0.1:49737
Mar 05 19:57:23 volumio mpd[1672]: client: [0] closed
Mar 05 19:57:23 volumio mpd[1672]: client: [2] opened from ::ffff:127.0.0.1:49738
Mar 05 19:57:23 volumio mpd[1672]: client: [1] closed
Mar 05 19:57:23 volumio mpd[1672]: client: [3] opened from ::ffff:127.0.0.1:49739
Mar 05 19:57:23 volumio mpd[1672]: client: [2] closed
Mar 05 19:57:23 volumio mpd[1672]: client: [4] opened from ::ffff:127.0.0.1:49743
Mar 05 19:57:23 volumio mpd[1672]: client: [3] closed
Mar 05 19:57:23 volumio mpd[1672]: client: [5] opened from ::ffff:127.0.0.1:49755
Mar 05 19:57:23 volumio mpd[1672]: alsa_mixer: Failed to read mixer for ‘alsa’: no such mixer control: PCM
Mar 05 19:57:23 volumio volumio[1691]: info:
[/code]

Hopefully this helps.

Edit:

I just connected the drive directly to volumio, did a restart and the result is the same. It stops at the same track.

Mar 05 20:19:08 volumio mpd[616]: update: added USB/14bb5605-54d0-42a9-91e5-7203d76ff6db/Musik/MP3/M/Mondo Generator/Mondo Generator - 2006 - Dead Planet, SonicSlowMotionTrails/07 - Track  7.mp3
Mar 05 20:19:08 volumio kernel: Unhandled fault: alignment fault (0x92000021) at 0x00000000eea5ed06
Mar 05 20:19:08 volumio systemd[1]: mpd.service: main process exited, code=killed, status=7/BUS
Mar 05 20:19:08 volumio systemd[1]: Unit mpd.service entered failed state.
Mar 05 20:19:08 volumio systemd[1]: mpd.service holdoff time over, scheduling restart.
Mar 05 20:19:08 volumio systemd[1]: Stopping Music Player Daemon...
Mar 05 20:19:08 volumio systemd[1]: Starting Music Player Daemon...
Mar 05 20:19:08 volumio systemd[1]: Started Music Player Daemon.
Mar 05 20:19:10 volumio mpd[1400]: zeroconf: No global port, disabling zeroconf
Mar 05 20:19:10 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 05 20:19:10 volumio volumio[885]: MPD error: Error: This socket has been ended by the other party
Mar 05 20:19:10 volumio volumio[885]: MPD error: Error: This socket has been ended by the other party
Mar 05 20:19:10 volumio volumio[885]: MPD error: Error: This socket has been ended by the other party
Mar 05 20:19:14 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 05 20:19:14 volumio volumio[885]: MPD error: Error: This socket has been ended by the other party
Mar 05 20:19:14 volumio volumio[885]: MPD error: Error: This socket has been ended by the other party
Mar 05 20:19:14 volumio volumio[885]: MPD error: Error: This socket has been ended by the other party


Mar 05 20:19:18 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Mar 05 20:19:18 volumio volumio[885]: assert.js:85
Mar 05 20:19:18 volumio volumio[885]: throw new assert.AssertionError({
Mar 05 20:19:18 volumio volumio[885]: ^
Mar 05 20:19:18 volumio volumio[885]: AssertionError: false == true
Mar 05 20:19:18 volumio volumio[885]: at MpdClient.sendCommand (/volumio/node_modules/mpd/index.js:86:10)
Mar 05 20:19:18 volumio volumio[885]: at ControllerMpd.getMyCollectionStats (/volumio/app/plugins/music_service/mpd/index.js:2256:20)
Mar 05 20:19:18 volumio volumio[885]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:931:29)
Mar 05 20:19:18 volumio volumio[885]: at Socket.<anonymous> (/volumio/app/plugins/user_interface/websocket/index.js:1122:43)
Mar 05 20:19:18 volumio volumio[885]: at emitTwo (events.js:106:13)
Mar 05 20:19:18 volumio volumio[885]: at Socket.emit (events.js:191:7)
Mar 05 20:19:18 volumio volumio[885]: at Socket.onevent (/volumio/node_modules/socket.io/lib/socket.js:348:8)
Mar 05 20:19:18 volumio volumio[885]: at Socket.onpacket (/volumio/node_modules/socket.io/lib/socket.js:308:12)
Mar 05 20:19:18 volumio volumio[885]: at /volumio/node_modules/socket.io/lib/client.js:195:16
Mar 05 20:19:18 volumio volumio[885]: at _combinedTickCallback (internal/process/next_tick.js:67:7)
Mar 05 20:19:18 volumio volumio[885]: at process._tickCallback (internal/process/next_tick.js:98:9)
Mar 05 20:19:19 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE
Mar 05 20:19:19 volumio systemd[1]: Unit volumio.service entered failed state.
Mar 05 20:19:19 volumio systemd[1]: Starting dynamicswap.service...
Mar 05 20:19:19 volumio systemd[1]: Started dynamicswap.service.
Mar 05 20:19:19 volumio systemd[1]: volumio.service holdoff time over, scheduling restart.
Mar 05 20:19:19 volumio systemd[1]: Starting dynamicswap.service...
Mar 05 20:19:19 volumio systemd[1]: Started dynamicswap.service.
Mar 05 20:19:19 volumio systemd[1]: Stopping Volumio Backend Module...
Mar 05 20:19:19 volumio systemd[1]: Starting Volumio Backend Module...
Mar 05 20:19:19 volumio systemd[1]: Started Volumio Backend Module.
Mar 05 20:19:20 volumio mpd[1400]: client: [0] opened from ::ffff:127.0.0.1:34761
Mar 05 20:19:20 volumio mpd[1400]: client: [1] opened from ::ffff:127.0.0.1:34762
Mar 05 20:19:20 volumio mpd[1400]: client: [2] opened from ::ffff:127.0.0.1:34763
Mar 05 20:19:20 volumio mpd[1400]: alsa_mixer: Failed to read mixer for 'alsa': no such mixer control: PCM
Mar 05 20:19:20 volumio mpd[1400]: client: [1] closed
Mar 05 20:19:20 volumio mpd[1400]: client: [3] opened from ::ffff:127.0.0.1:34764
Mar 05 20:19:20 volumio mpd[1400]: client: [2] closed
Mar 05 20:19:20 volumio mpd[1400]: client: [3] closed
Mar 05 20:19:22 volumio volumio[1418]: info: -------------------------------------------
Mar 05 20:19:22 volumio volumio[1418]: info: -----            Volumio2              ----
Mar 05 20:19:22 volumio volumio[1418]: info: -------------------------------------------
Mar 05 20:19:22 volumio volumio[1418]: info: -----          System startup          ----
Mar 05 20:19:22 volumio volumio[1418]: info: -------------------------------------------