Volume issue with Allo Piano on latest update

Volumio Development Bug reports
1

Hello !

I updated my 2 Raspberry, and one of them i getting some troubles with the latest volumio update : when i change the volume in the UI, the whole interface gets unresponsive, showing the rolling stuff for 5 seconds…

The PI running iQaudio has no issue.

Any suggestion appreciated :unamused:
Screen Shot 2018-02-11 at 20.06.31.png

2

Can you please send logs after the issue occurs?

volumio.github.io/docs/User_Man … oting.html

3

Hi,
I have the same issue (PI3 + Kali + Piano 2.1).
To take logs is a barely possible, because whole system is not responding to requests from UI. It seems that system is working just in a few seconds windows and then not working. Also “Play” LEDs on Piano 2.1 are blinking periodically.
I had not had the issue until I updated Volumio to the newest version.

4

Do this please :
Connect via ssh, then type
sudo journalctl -f

Paste the log when you see it crash here

So I can troubleshoot and release a fix asap. Thanks

5

Thanks !

But no stress here… It’s more a freeze of the UI and not a crash, nothing really urgent :smiley:

Thanks again for your fantastic job.

S.

6

Yes but I am keen to solve it :wink:

7

Piano 2.1 Ui issues. Album art not syncing, queue auto play not working. Volume control cause ui timeout.

When timeout returns, album art show first queue track.

8

login as: volumio
volumio@192.168.1.117’s password:
___
/_ \ __
__ __ //\ \ __ __ ___ ___ /_\ ___
/\ /\ \ / __\\ \ \ /\ \/\ \ /' __ __\/\ \ / __
\ \ _/ |/\ \L\ \\ _\ \ _\ /\ /\ /\ \ \ /\ \L\
\ _/ \ _//_\ _/\ _\ _\ _\ _\ _/
// // // // /////////___/

         Free Audiophile Linux Music Player - Version 2.0

      C 2015 Michelangelo Guarise - Volumio Team - Volumio.org

Volumio Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
volumio@volumio:~$ sudo journalctl -f
– Logs begin at Sun 2018-02-11 21:42:53 UTC. –
Feb 11 21:48:12 volumio systemd[2818]: Starting Basic System.
Feb 11 21:48:12 volumio systemd[2818]: Reached target Basic System.
Feb 11 21:48:12 volumio systemd[2818]: Starting Default.
Feb 11 21:48:12 volumio systemd[2818]: Reached target Default.
Feb 11 21:48:12 volumio systemd[2818]: Startup finished in 70ms.
Feb 11 21:48:12 volumio systemd[1]: Started User Manager for UID 1000.
Feb 11 21:48:12 volumio sshd[2342]: lastlog_openseek: Couldn’t stat /var/log/las tlog: No such file or directory
Feb 11 21:48:12 volumio sshd[2342]: lastlog_openseek: Couldn’t stat /var/log/las tlog: No such file or directory
Feb 11 21:48:23 volumio sudo[2909]: volumio : TTY=pts/0 ; PWD=/home/volumio ; US ER=root ; COMMAND=/bin/journalctl -f
Feb 11 21:48:23 volumio sudo[2909]: pam_unix(sudo:session): session opened for u ser root by volumio(uid=0)

Feb 11 21:48:52 volumio volumio[1380]: info:
Feb 11 21:48:52 volumio volumio[1380]: ---------------------------- MPD announce s state update: player
Feb 11 21:48:53 volumio volumio[1380]: info: ControllerMpd::getState
Feb 11 21:48:53 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand statu s
Feb 11 21:48:53 volumio volumio[1380]: info: sending command…
Feb 11 21:48:53 volumio volumio[1380]: info: parsing response…
Feb 11 21:48:53 volumio volumio[1380]: info: ControllerMpd::parseState
Feb 11 21:48:53 volumio volumio[1380]: info: ControllerMpd::pushState
Feb 11 21:48:53 volumio volumio[1380]: info: CoreCommandRouter::servicePushState
Feb 11 21:48:53 volumio volumio[1380]: info: CoreStateMachine::syncState
Feb 11 21:48:53 volumio volumio[1380]: info: CorePlayQueue::getTrack 0
Feb 11 21:48:53 volumio volumio[1380]: info: STATE SERVICE {“status”:“stop”,“pos ition”:null,“seek”:null,“duration”:null,“samplerate”:null,“bitdepth”:null,“chann els”:null,“random”:false,“updatedb”:false,“repeat”:false,“isStreaming”:false,“ti tle”:null,“artist”:null,“album”:null,“uri”:null}
Feb 11 21:48:53 volumio volumio[1380]: info: CURRENT POSITION 0
Feb 11 21:48:53 volumio volumio[1380]: info: CoreStateMachine::syncState state Service stop
Feb 11 21:48:53 volumio volumio[1380]: info: CoreStateMachine::syncState curre ntStatus stop
Feb 11 21:48:53 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:48:53 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:48:53 volumio volumio[1380]: info: CorePlayQueue::getTrack 0
Feb 11 21:48:53 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:48:53 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 11 21:48:53 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:48:53 volumio volumio[1380]: info: No code
Feb 11 21:48:53 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:48:53 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:48:53 volumio volumio[1380]: info: CorePlayQueue::getTrack 0
Feb 11 21:48:53 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:48:53 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 11 21:48:53 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:48:53 volumio volumio[1380]: info: ------------------------------ 258m s
Feb 11 21:48:53 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd” ,“uri”:“mnt/NAS/Pop/ABC - The Lexicon Of Love/06 - The Look Of Love (Part One).f lac”,“favourite”:false}
Feb 11 21:48:53 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd” ,“uri”:“mnt/NAS/Pop/ABC - The Lexicon Of Love/06 - The Look Of Love (Part One).f lac”,“favourite”:false}
^Cvolumio@volumio:~$ !! > logfile
sudo journalctl -f > logfile
^Cvolumio@volumio:~$ more logfile
– Logs begin at Sun 2018-02-11 21:42:53 UTC. –
Feb 11 21:48:53 volumio volumio[1380]: info: CorePlayQueue::getTrack 0
Feb 11 21:48:53 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:48:53 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
volumiodiscovery , saveDeviceInfo
Feb 11 21:48:53 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:48:53 volumio volumio[1380]: info: ------------------------------ 258m
s
Feb 11 21:48:53 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/ABC - The Lexicon Of Love/06 - The Look Of Love (Part One).f
lac”,“favourite”:false}
Feb 11 21:48:53 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/ABC - The Lexicon Of Love/06 - The Look Of Love (Part One).f
lac”,“favourite”:false}
Feb 11 21:50:42 volumio sudo[2909]: pam_unix(sudo:session): session closed for u
ser root
Feb 11 21:50:51 volumio sudo[3927]: volumio : TTY=pts/0 ; PWD=/home/volumio ; US
ER=root ; COMMAND=/bin/journalctl -f
Feb 11 21:50:51 volumio sudo[3927]: pam_unix(sudo:session): session opened for u
ser root by volumio(uid=0)
Feb 11 21:50:56 volumio volumio[1380]: info: CoreCommandRouter::volumioGetQueue
Feb 11 21:50:56 volumio volumio[1380]: info: CoreStateMachine::getQueue
Feb 11 21:50:56 volumio volumio[1380]: info: CorePlayQueue::getQueue
Feb 11 21:50:58 volumio volumio[1380]: info: CoreCommandRouter::volumioPlay
Feb 11 21:50:58 volumio volumio[1380]: UNSET VOLATILE
Feb 11 21:50:58 volumio volumio[1380]: info: CoreStateMachine::play index 6
Feb 11 21:50:58 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateS
ervice undefined
Feb 11 21:50:58 volumio volumio[1380]: info: CoreStateMachine::stop
Feb 11 21:50:58 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateS
ervice undefined
Feb 11 21:50:58 volumio volumio[1380]: UNSET VOLATILE
Feb 11 21:50:58 volumio volumio[1380]: info: CoreStateMachine::play index undefi
ned
Feb 11 21:50:58 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateS
ervice undefined
Feb 11 21:50:58 volumio volumio[1380]: info: CorePlayQueue::getTrack 6
Feb 11 21:50:58 volumio volumio[1380]: info: CoreStateMachine::startPlaybackTime
r
Feb 11 21:50:58 volumio volumio[1380]: info: CorePlayQueue::getTrack 6
Feb 11 21:50:58 volumio volumio[1380]: info: ControllerMpd::clearAddPlayTracks N
AS/Pop/Celine Dion - Complete Best (2008)/06. Because You Loved Me.flac
Feb 11 21:50:58 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand stop
Feb 11 21:50:58 volumio volumio[1380]: info: sending command…
Feb 11 21:50:58 volumio volumio[1380]: info: parsing response…
Feb 11 21:50:58 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand clear
Feb 11 21:50:58 volumio volumio[1380]: info: sending command…
Feb 11 21:50:58 volumio volumio[1380]: info:
Feb 11 21:50:58 volumio volumio[1380]: ---------------------------- MPD announce
s system playlist update
Feb 11 21:50:58 volumio volumio[1380]: info: Ignoring MPD Status Update
Feb 11 21:50:58 volumio volumio[1380]: info: parsing response…
Feb 11 21:50:58 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand add "
NAS/Pop/Celine Dion - Complete Best (2008)/06. Because You Loved Me.flac"
Feb 11 21:50:58 volumio volumio[1380]: info: sending command…
Feb 11 21:50:58 volumio volumio[1380]: info:
Feb 11 21:50:58 volumio volumio[1380]: info:
Feb 11 21:50:58 volumio volumio[1380]: ---------------------------- MPD announce
s system playlist update
Feb 11 21:50:58 volumio volumio[1380]: info: Ignoring MPD Status Update
Feb 11 21:50:58 volumio volumio[1380]: info: ------------------------------ 22ms
Feb 11 21:50:58 volumio volumio[1380]: info: parsing response…
Feb 11 21:50:58 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand play
Feb 11 21:50:58 volumio volumio[1380]: info: sending command…
Feb 11 21:50:58 volumio volumio[1380]: info: ------------------------------ 15ms
Feb 11 21:50:58 volumio volumio[1380]: info: parsing response…
Feb 11 21:50:58 volumio volumio[1380]: info:
Feb 11 21:50:58 volumio volumio[1380]: ---------------------------- MPD announce
s state update: player
Feb 11 21:50:58 volumio volumio[1380]: info: ControllerMpd::getState
Feb 11 21:50:58 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand statu
s
Feb 11 21:50:58 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Feb 11 21:50:58 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Feb 11 21:50:58 volumio volumio[1380]: info: sending command…
Feb 11 21:50:59 volumio volumio[1380]: info:
Feb 11 21:50:59 volumio volumio[1380]: ---------------------------- MPD announce
s state update: player
Feb 11 21:50:59 volumio volumio[1380]: info: parsing response…
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::getState
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand statu
s
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::parseState
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand playl
istinfo
Feb 11 21:50:59 volumio volumio[1380]: info: sending command…
Feb 11 21:50:59 volumio volumio[1380]: info: sending command…
Feb 11 21:50:59 volumio volumio[1380]: info: parsing response…
Feb 11 21:50:59 volumio volumio[1380]: info: parsing response…
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::parseState
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand playl
istinfo
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::parseTrackInfo
Feb 11 21:50:59 volumio volumio[1380]: info: sending command…
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::pushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreCommandRouter::servicePushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::syncState
Feb 11 21:50:59 volumio volumio[1380]: info: CorePlayQueue::getTrack 6
Feb 11 21:50:59 volumio volumio[1380]: info: STATE SERVICE {“status”:“play”,“pos
ition”:0,“seek”:0,“duration”:275,“samplerate”:“44.1 KHz”,“bitdepth”:“16 bit”,“ch
annels”:2,“random”:false,“updatedb”:false,“repeat”:false,“isStreaming”:false,“ti
tle”:“Because You Loved Me”,“artist”:“Celine Dion”,“album”:“Complete Best”,“uri”
:“NAS/Pop/Celine Dion - Complete Best (2008)/06. Because You Loved Me.flac”,“tra
ckType”:“flac”}
Feb 11 21:50:59 volumio volumio[1380]: info: CURRENT POSITION 6
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::syncState state
Service play
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::syncState curre
ntStatus stop
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:50:59 volumio volumio[1380]: info: CorePlayQueue::getTrack 6
Feb 11 21:50:59 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
volumiodiscovery , saveDeviceInfo
Feb 11 21:50:59 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:50:59 volumio volumio[1380]: info: ------------------------------ 346m
s
Feb 11 21:50:59 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/Celine Dion - Complete Best (2008)/06. Because You Loved Me.
flac”,“favourite”:false}
Feb 11 21:50:59 volumio volumio[1380]: info: parsing response…
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::parseTrackInfo
Feb 11 21:50:59 volumio volumio[1380]: info: ControllerMpd::pushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreCommandRouter::servicePushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::syncState
Feb 11 21:50:59 volumio volumio[1380]: info: CorePlayQueue::getTrack 6
Feb 11 21:50:59 volumio volumio[1380]: info: STATE SERVICE {“status”:“play”,“pos
ition”:0,“seek”:185,“duration”:275,“samplerate”:“44.1 KHz”,“bitdepth”:“16 bit”,"
channels":2,“random”:false,“updatedb”:false,“repeat”:false,“isStreaming”:false,"
title":“Because You Loved Me”,“artist”:“Celine Dion”,“album”:“Complete Best”,“ur
i”:“NAS/Pop/Celine Dion - Complete Best (2008)/06. Because You Loved Me.flac”,“t
rackType”:“flac”}
Feb 11 21:50:59 volumio volumio[1380]: info: CURRENT POSITION 6
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::syncState state
Service play
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::syncState curre
ntStatus play
Feb 11 21:50:59 volumio volumio[1380]: info: Received an update from plaugin. ex
tracting info from payload
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:50:59 volumio volumio[1380]: info: CorePlayQueue::getTrack 6
Feb 11 21:50:59 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
volumiodiscovery , saveDeviceInfo
Feb 11 21:50:59 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:50:59 volumio volumio[1380]: info: CorePlayQueue::getTrack 6
Feb 11 21:50:59 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:50:59 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
volumiodiscovery , saveDeviceInfo
Feb 11 21:50:59 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:50:59 volumio volumio[1380]: info: ------------------------------ 695m
s
Feb 11 21:50:59 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/Celine Dion - Complete Best (2008)/06. Because You Loved Me.
flac”,“favourite”:false}
Feb 11 21:50:59 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/Celine Dion - Complete Best (2008)/06. Because You Loved Me.
flac”,“favourite”:false}
Feb 11 21:51:13 volumio volumio[1380]: info: CoreCommandRouter::volumioGetBrowse
Sources
Feb 11 21:51:15 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
mpd , handleBrowseUri
Feb 11 21:51:15 volumio volumio[1380]: info: CURURI: music-library
Feb 11 21:51:16 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
mpd , handleBrowseUri
Feb 11 21:51:16 volumio volumio[1380]: info: CURURI: music-library/NAS
Feb 11 21:51:17 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
mpd , handleBrowseUri
Feb 11 21:51:17 volumio volumio[1380]: info: CURURI: music-library/NAS/Pop
Feb 11 21:51:30 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
mpd , handleBrowseUri
Feb 11 21:51:30 volumio volumio[1380]: info: CURURI: music-library/NAS/Pop/Celin
e Dion - Complete Best (2008)
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::volumioAddQueueI
tems
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::addQueueItems
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::addQueueItems
Feb 11 21:51:35 volumio volumio[1380]: info: Adding Item to queue: music-library
/NAS/Pop/Celine Dion - Complete Best (2008)/10. Where Does My Heart Beat Now.fla
c
Feb 11 21:51:35 volumio volumio[1380]: info: Exploding uri music-library/NAS/Pop
/Celine Dion - Complete Best (2008)/10. Where Does My Heart Beat Now.flac in ser
vice mpd
Feb 11 21:51:35 volumio volumio[1380]: info: ALBUMART /albumart?cacheid=636&path
=%2Fmnt%2FNAS%2FPop%2FCeline%20Dion%20-%20Complete%20Best%20(2008)%2F10.%20Where
%20Does%20My%20Heart%20Beat%20Now.flac&metadata=false
Feb 11 21:51:35 volumio volumio[1380]: info: URI /mnt/NAS/Pop/Celine Dion - Comp
lete Best (2008)/10. Where Does My Heart Beat Now.flac
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::saveQueue
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::volumioPushQueue
Feb 11 21:51:35 volumio volumio[1380]: info: interfaceApi::pushQueue
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::volumioPlay
Feb 11 21:51:35 volumio volumio[1380]: UNSET VOLATILE
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::play index 7
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateS
ervice undefined
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::stop
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateS
ervice undefined
Feb 11 21:51:35 volumio volumio[1380]: UNSET VOLATILE
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::stPlaybackTimer
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::updateTrackBlock
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::getTrackBlock
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::getTrack 6
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
volumiodiscovery , saveDeviceInfo
Feb 11 21:51:35 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::serviceStop
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::getTrack 6
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::serviceStop
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::stop
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand stop
Feb 11 21:51:35 volumio volumio[1380]: info: sending command…
Feb 11 21:51:35 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/Celine Dion - Complete Best (2008)/06. Because You Loved Me.
flac”,“favourite”:false}
Feb 11 21:51:35 volumio volumio[1380]: info:
Feb 11 21:51:35 volumio volumio[1380]: ---------------------------- MPD announce
s state update: player
Feb 11 21:51:35 volumio volumio[1380]: info: parsing response…
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::getState
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand statu
s
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::play index undefi
ned
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::setConsumeUpdateS
ervice undefined
Feb 11 21:51:35 volumio volumio[1380]: info: sending command…
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::getTrack 7
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::startPlaybackTime
r
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::getTrack 7
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::clearAddPlayTracks N
AS/Pop/Celine Dion - Complete Best (2008)/10. Where Does My Heart Beat Now.flac
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand stop
Feb 11 21:51:35 volumio volumio[1380]: info: sending command…
Feb 11 21:51:35 volumio volumio[1380]: info: parsing response…
Feb 11 21:51:35 volumio volumio[1380]: info: parsing response…
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::parseState
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand playl
istinfo
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand clear
Feb 11 21:51:35 volumio volumio[1380]: info: sending command…
Feb 11 21:51:35 volumio volumio[1380]: info: sending command…
Feb 11 21:51:35 volumio volumio[1380]: info:
Feb 11 21:51:35 volumio volumio[1380]: ---------------------------- MPD announce
s system playlist update
Feb 11 21:51:35 volumio volumio[1380]: info: Ignoring MPD Status Update
Feb 11 21:51:35 volumio volumio[1380]: info: parsing response…
Feb 11 21:51:35 volumio volumio[1380]: info: parsing response…
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::parseTrackInfo
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand add "
NAS/Pop/Celine Dion - Complete Best (2008)/10. Where Does My Heart Beat Now.flac
"
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::pushState
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::servicePushState
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::syncState
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::getTrack 7
Feb 11 21:51:35 volumio volumio[1380]: info: STATE SERVICE {“status”:“stop”,“pos
ition”:0,“seek”:null,“duration”:null,“samplerate”:null,“bitdepth”:null,“channels
“:null,“random”:false,“updatedb”:false,“repeat”:false,“isStreaming”:false,“title
“:“Because You Loved Me”,“artist”:“Celine Dion”,“album”:“Complete Best”,“uri”:“N
AS/Pop/Celine Dion - Complete Best (2008)/06. Because You Loved Me.flac”,“trackT
ype”:“flac”}
Feb 11 21:51:35 volumio volumio[1380]: info: CURRENT POSITION 7
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::syncState state
Service stop
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::syncState curre
ntStatus stop
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::getTrack 7
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
volumiodiscovery , saveDeviceInfo
Feb 11 21:51:35 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:51:35 volumio volumio[1380]: info: No code
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:51:35 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:51:35 volumio volumio[1380]: info: CorePlayQueue::getTrack 7
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:51:35 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
volumiodiscovery , saveDeviceInfo
Feb 11 21:51:35 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:51:35 volumio volumio[1380]: info: sending command…
Feb 11 21:51:35 volumio volumio[1380]: info: ------------------------------ 319m
s
Feb 11 21:51:35 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/Celine Dion - Complete Best (2008)/10. Where Does My Heart B
eat Now.flac”,“favourite”:false}
Feb 11 21:51:35 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/Celine Dion - Complete Best (2008)/10. Where Does My Heart B
eat Now.flac”,“favourite”:false}
Feb 11 21:51:35 volumio volumio[1380]: info:
Feb 11 21:51:35 volumio volumio[1380]: info:
Feb 11 21:51:35 volumio volumio[1380]: ---------------------------- MPD announce
s system playlist update
Feb 11 21:51:35 volumio volumio[1380]: info: Ignoring MPD Status Update
Feb 11 21:51:35 volumio volumio[1380]: info: ------------------------------ 308m
s
Feb 11 21:51:35 volumio volumio[1380]: info: parsing response…
Feb 11 21:51:35 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand play
Feb 11 21:51:35 volumio volumio[1380]: info: sending command…
Feb 11 21:51:35 volumio volumio[1380]: info: ------------------------------ 25ms
Feb 11 21:51:35 volumio volumio[1380]: info: parsing response…
Feb 11 21:51:36 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Feb 11 21:51:36 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Feb 11 21:51:36 volumio volumio[1380]: info:
Feb 11 21:51:36 volumio volumio[1380]: ---------------------------- MPD announce
s state update: player
Feb 11 21:51:36 volumio volumio[1380]: info: ControllerMpd::getState
Feb 11 21:51:36 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand statu
s
Feb 11 21:51:36 volumio volumio[1380]: info: sending command…
Feb 11 21:51:36 volumio volumio[1380]: info: parsing response…
Feb 11 21:51:36 volumio volumio[1380]: info: ControllerMpd::parseState
Feb 11 21:51:36 volumio volumio[1380]: info: ControllerMpd::sendMpdCommand playl
istinfo
Feb 11 21:51:36 volumio volumio[1380]: info: sending command…
Feb 11 21:51:36 volumio volumio[1380]: info: parsing response…
Feb 11 21:51:36 volumio volumio[1380]: info: ControllerMpd::parseTrackInfo
Feb 11 21:51:36 volumio volumio[1380]: info: ControllerMpd::pushState
Feb 11 21:51:36 volumio volumio[1380]: info: CoreCommandRouter::servicePushState
Feb 11 21:51:36 volumio volumio[1380]: info: CoreStateMachine::syncState
Feb 11 21:51:36 volumio volumio[1380]: info: CorePlayQueue::getTrack 7
Feb 11 21:51:36 volumio volumio[1380]: info: STATE SERVICE {“status”:“play”,“pos
ition”:0,“seek”:185,“duration”:275,“samplerate”:“44.1 KHz”,“bitdepth”:“16 bit”,”
channels”:2,“random”:false,“updatedb”:false,“repeat”:false,“isStreaming”:false,”
title”:“Where Does My Heart Beat Now”,“artist”:“Celine Dion”,“album”:“Complete B
est”,“uri”:“NAS/Pop/Celine Dion - Complete Best (2008)/10. Where Does My Heart B
eat Now.flac”,“trackType”:“flac”}
Feb 11 21:51:36 volumio volumio[1380]: info: CURRENT POSITION 7
Feb 11 21:51:36 volumio volumio[1380]: info: CoreStateMachine::syncState state
Service play
Feb 11 21:51:36 volumio volumio[1380]: info: CoreStateMachine::syncState curre
ntStatus stop
Feb 11 21:51:36 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:51:36 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:51:36 volumio volumio[1380]: info: CorePlayQueue::getTrack 7
Feb 11 21:51:36 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:51:36 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
volumiodiscovery , saveDeviceInfo
Feb 11 21:51:36 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:51:37 volumio volumio[1380]: info: ------------------------------ 271m
s
Feb 11 21:51:37 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/Celine Dion - Complete Best (2008)/10. Where Does My Heart B
eat Now.flac”,“favourite”:false}
Feb 11 21:51:41 volumio volumio[1380]: info: VolumeController::SetAlsaVolume-
Feb 11 21:51:41 volumio volumio[1380]: null
Feb 11 21:51:41 volumio volumio[1380]: info: VolumeController::Volume 41
Feb 11 21:51:41 volumio volumio[1380]: info: CoreStateMachine::pushState
Feb 11 21:51:41 volumio volumio[1380]: info: CoreStateMachine::getState
Feb 11 21:51:41 volumio volumio[1380]: info: CorePlayQueue::getTrack 7
Feb 11 21:51:41 volumio volumio[1380]: info: CoreCommandRouter::volumioPushState
Feb 11 21:51:41 volumio volumio[1380]: info: CoreCommandRouter::executeOnPlugin:
volumiodiscovery , saveDeviceInfo
Feb 11 21:51:41 volumio volumio[1380]: info: interfaceApi::pushState
Feb 11 21:51:42 volumio volumio[1380]: info: Pushing Favourites {“service”:“mpd”
,“uri”:“mnt/NAS/Pop/Celine Dion - Complete Best (2008)/10. Where Does My Heart B
eat Now.flac”,“favourite”:false}
Feb 11 21:51:42 volumio volumio[1380]: info: VolumeController::Volume 41
Feb 11 21:51:42 volumio volumio[1380]: /volumio/node_modules/kew/kew.js:140
Feb 11 21:51:42 volumio volumio[1380]: if (this._error || this._hasData) throw n
ew Error(“Unable to resolve or reject the same promise twice”)
Feb 11 21:51:42 volumio volumio[1380]: ^
Feb 11 21:51:42 volumio volumio[1380]: Error: Unable to resolve or reject the sa
me promise twice
Feb 11 21:51:42 volumio volumio[1380]: at Promise.resolve (/volumio/node_modules
/kew/kew.js:140:43)
Feb 11 21:51:42 volumio volumio[1380]: at /volumio/app/volumecontrol.js:362:27
Feb 11 21:51:42 volumio volumio[1380]: at /volumio/app/volumecontrol.js:199:25
Feb 11 21:51:42 volumio volumio[1380]: at ChildProcess. (/volumio/app
/volumecontrol.js:76:4)
Feb 11 21:51:42 volumio volumio[1380]: at emitTwo (events.js:106:13)
Feb 11 21:51:42 volumio volumio[1380]: at ChildProcess.emit (events.js:191:7)
Feb 11 21:51:42 volumio volumio[1380]: at maybeClose (internal/child_process.js:
891:16)
Feb 11 21:51:42 volumio volumio[1380]: at Socket. (internal/child_pro
cess.js:342:11)
Feb 11 21:51:42 volumio volumio[1380]: at emitOne (events.js:96:13)
Feb 11 21:51:42 volumio volumio[1380]: at Socket.emit (events.js:188:7)
Feb 11 21:51:42 volumio volumio[1380]: at Pipe._handle.close [as _onclose] (net.
js:497:12)
Feb 11 21:51:42 volumio systemd[1]: volumio.service: main process exited, code=e
xited, status=1/FAILURE
Feb 11 21:51:42 volumio volumio-remote-updater[644]: [2018-02-11 21:51:42] [erro
r] handle_read_frame error: websocketpp.transport:7 (End of File)
Feb 11 21:51:42 volumio volumio-remote-updater[644]: [2018-02-11 21:51:42] [disc
onnect] Disconnect close local:[1006,End of File] remote:[1006]
Feb 11 21:51:42 volumio systemd[1]: Unit volumio.service entered failed state.
Feb 11 21:51:42 volumio systemd[1]: Starting dynamicswap service…
Feb 11 21:51:42 volumio systemd[1]: Started dynamicswap service.
Feb 11 21:51:42 volumio systemd[1]: volumio.service holdoff time over, schedulin
g restart.
Feb 11 21:51:42 volumio systemd[1]: Starting dynamicswap service…
Feb 11 21:51:42 volumio systemd[1]: Started dynamicswap service.
Feb 11 21:51:42 volumio systemd[1]: Stopping Volumio Backend Module…
Feb 11 21:51:42 volumio systemd[1]: Starting Volumio Backend Module…
Feb 11 21:51:42 volumio systemd[1]: Started Volumio Backend Module.
Feb 11 21:51:47 volumio volumio-remote-updater[644]: [2018-02-11 21:51:47] [conn
ect] Successful connection
Feb 11 21:51:47 volumio volumio[4297]: info: -----------------------------------

Feb 11 21:51:47 volumio volumio[4297]: info: ----- Volumio2
----
Feb 11 21:51:47 volumio volumio[4297]: info: -----------------------------------

Feb 11 21:51:47 volumio volumio[4297]: info: ----- System startup
----
Feb 11 21:51:47 volumio volumio[4297]: info: -----------------------------------

Feb 11 21:51:47 volumio volumio[4297]: info: Plugin folders cleanup
Feb 11 21:51:47 volumio volumio[4297]: info: Scanning into folder /volumio/app/p
lugins/
Feb 11 21:51:47 volumio volumio[4297]: info: Scanning category audio_interface
Feb 11 21:51:47 volumio volumio[4297]: info: Scanning category miscellanea
Feb 11 21:51:47 volumio volumio[4297]: info: Scanning category music_service
Feb 11 21:51:47 volumio volumio[4297]: info: Scanning category plugins.json
Feb 11 21:51:47 volumio volumio[4297]: info: Scanning category system_controller
Feb 11 21:51:47 volumio volumio[4297]: info: Scanning category user_interface
Feb 11 21:51:47 volumio volumio[4297]: info: Scanning into folder /data/plugins/
Feb 11 21:51:47 volumio volumio[4297]: info: Scanning category miscellanea
Feb 11 21:51:47 volumio volumio[4297]: info: Plugin folders cleanup completed
Feb 11 21:51:47 volumio volumio[4297]: info: Loading plugins from folder /volumi
o/app/plugins/
Feb 11 21:51:47 volumio volumio[4297]: info: Loading plugins from folder /data/p
lugins/
Feb 11 21:51:47 volumio volumio[4297]: info: Loading plugin “system”…
Feb 11 21:51:48 volumio volumio[4297]: info: Loading plugin “appearance”…
Feb 11 21:51:50 volumio volumio[4297]: info: Loading plugin “network”…
Feb 11 21:51:50 volumio volumio[4297]: info: Loading plugin “services”…
Feb 11 21:51:50 volumio volumio[4297]: info: Loading plugin “alsa_controller”…
Feb 11 21:51:50 volumio volumio[4297]: info: Loading plugin “wizard”…
Feb 11 21:51:50 volumio volumio[4297]: info: Loading plugin “volumio_command_lin
e_client”…
Feb 11 21:51:50 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
alsa_controller , getConfigParam
Feb 11 21:51:50 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
alsa_controller , getConfigParam
Feb 11 21:51:50 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
alsa_controller , getConfigParam
Feb 11 21:51:50 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
alsa_controller , getConfigParam
Feb 11 21:51:51 volumio volumio[4297]: info: Loading plugin “upnp”…
Feb 11 21:51:51 volumio volumio[4297]: info: [1518385911050] Starting Upmpd Daem
on
Feb 11 21:51:51 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
system , registerCallback
Feb 11 21:51:51 volumio volumio[4297]: info: Loading plugin “mpd”…
Feb 11 21:51:51 volumio volumio[4297]: info: Loading plugin “upnp_browser”…
Feb 11 21:51:52 volumio volumio[4297]: info: Loading plugin “networkfs”…
Feb 11 21:51:52 volumio volumio[4297]: info: Cannot mount NAS NM10 at system boo
t, trial number 1 ,retrying in 5 seconds
Feb 11 21:51:52 volumio volumio[4297]: info: Cannot mount NAS Pop at system boot
, trial number 1 ,retrying in 5 seconds
Feb 11 21:51:52 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
system , registerCallback
Feb 11 21:51:52 volumio volumio[4297]: info: Loading plugin “alarm-clock”…
Feb 11 21:51:53 volumio volumio[4297]: info: Loading plugin “airplay_emulation”.
…
Feb 11 21:51:53 volumio volumio[4297]: info: [1518385913224] Starting Shairport
Sync
Feb 11 21:51:53 volumio volumio[4297]: info: Loading plugin “last_100”…
Feb 11 21:51:53 volumio volumio[4297]: info: Loading plugin “webradio”…
Feb 11 21:51:53 volumio volumio[4297]: info: Loading plugin “i2s_dacs”…
Feb 11 21:51:53 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
system , getConfigParam
Feb 11 21:51:53 volumio volumio[4297]: info: Loading plugin “volumiodiscovery”…
.
Feb 11 21:51:53 volumio volumio[4297]: *** WARNING *** The program ‘node’ uses t
he Apple Bonjour compatibility layer of Avahi.
Feb 11 21:51:53 volumio node[4297]: *** WARNING *** The program ‘node’ uses the
Apple Bonjour compatibility layer of Avahi.
Feb 11 21:51:53 volumio node[4297]: *** WARNING *** Please fix your application
to use the native API of Avahi!
Feb 11 21:51:53 volumio node[4297]: *** WARNING *** For more information see <ht
tp://0pointer.de/avahi-compat?s=libdns_sd&e=node>
Feb 11 21:51:53 volumio node[4297]: *** WARNING *** The program ‘node’ called ‘D
NSServiceRegister()’ which is not supported (or only supported partially) in the
Apple Bonjour compatibility layer of Avahi.
Feb 11 21:51:53 volumio node[4297]: *** WARNING *** Please fix your application
to use the native API of Avahi!
Feb 11 21:51:53 volumio node[4297]: *** WARNING *** For more information see <ht
tp://0pointer.de/avahi-compat?s=libdns_ … ceRegister>
Feb 11 21:51:53 volumio volumio[4297]: *** WARNING *** Please fix your applicati
on to use the native API of Avahi!
Feb 11 21:51:53 volumio volumio[4297]: *** WARNING *** For more information see
http://0pointer.de/avahi-compat?s=libdns_sd&e=node
Feb 11 21:51:53 volumio volumio[4297]: *** WARNING *** The program ‘node’ called
‘DNSServiceRegister()’ which is not supported (or only supported partially) in
the Apple Bonjour compatibility layer of Avahi.
Feb 11 21:51:53 volumio volumio[4297]: *** WARNING *** Please fix your applicati
on to use the native API of Avahi!
Feb 11 21:51:53 volumio volumio[4297]: *** WARNING *** For more information see
http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister
Feb 11 21:51:53 volumio volumio[4297]: info: Applying required configuration par
ameters for plugin volumiodiscovery
Feb 11 21:51:53 volumio volumio[4297]: Discovery: StartAdv! undefined
Feb 11 21:51:53 volumio volumio[4297]: Discovery: Started advertising… Volumio

  • undefined
    Feb 11 21:51:53 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    system , registerCallback
    Feb 11 21:51:53 volumio volumio[4297]: info: Loading plugin “albumart”…
    Feb 11 21:51:53 volumio volumio[4297]: info: Plugin example_plugin is not enable
    d
    Feb 11 21:51:53 volumio volumio[4297]: info: Loading plugin “updater_comm”…
    Feb 11 21:51:54 volumio volumio[4297]: info: Plugin mpdemulation is not enabled
    Feb 11 21:51:54 volumio volumio[4297]: info: Loading plugin “rest_api”…
    Feb 11 21:51:54 volumio volumio[4297]: info: Loading plugin “websocket”…
    Feb 11 21:51:54 volumio volumio[4297]: info: Loading plugin “touch_display”…
    Feb 11 21:51:54 volumio volumio[4297]: info: ___________ START PLUGINS _________
    __
    Feb 11 21:51:54 volumio volumio[4297]: info: PLUGIN START: appearance
    Feb 11 21:51:54 volumio volumio[4297]: info: PLUGIN START: wizard
    Feb 11 21:51:54 volumio volumio[4297]: info: PLUGIN START: upnp_browser
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    mpd , getConfigParam
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::volumioAddToBrow
    seSources[object Object]
    Feb 11 21:51:54 volumio volumio[4297]: info: [1518385914544] CoreMusicLibrary::A
    dding element Media Servers
    Feb 11 21:51:54 volumio volumio[4297]: info: PLUGIN START: last_100
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::volumioAddToBrow
    seSources[object Object]
    Feb 11 21:51:54 volumio volumio[4297]: info: [1518385914590] CoreMusicLibrary::A
    dding element Last_100
    Feb 11 21:51:54 volumio volumio[4297]: info: PLUGIN START: webradio
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::volumioAddToBrow
    seSources[object Object]
    Feb 11 21:51:54 volumio volumio[4297]: info: [1518385914599] CoreMusicLibrary::A
    dding element Webradio
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    system , getSystemVersion
    Feb 11 21:51:54 volumio volumio[4297]: info: PLUGIN START: touch_display
    Feb 11 21:51:54 volumio volumio[4297]: info: Loading i18n strings for locale en
    Feb 11 21:51:54 volumio volumio[4297]: Updating browse sources language
    Feb 11 21:51:54 volumio volumio[4297]: Cannot find translation for sourceMedia S
    ervers
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    mpd , getConfigParam
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::initPlayerContro
    ls
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    alsa_controller , getConfigParam
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    alsa_controller , getAlsaCards
    Feb 11 21:51:54 volumio sudo[4353]: volumio : TTY=unknown ; PWD=/ ; USER=root ;
    COMMAND=/bin/systemctl start volumio-kiosk.service
    Feb 11 21:51:54 volumio sudo[4353]: pam_unix(sudo:session): session opened for u
    ser root by (uid=0)
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    alsa_controller , getConfigParam
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    alsa_controller , getConfigParam
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    alsa_controller , getConfigParam
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    alsa_controller , getConfigParam
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    alsa_controller , getConfigParam
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    mpd , getConfigParam
    Feb 11 21:51:54 volumio volumio[4297]: info: BOOT COMPLETED
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    system , getConfigParam
    Feb 11 21:51:54 volumio systemd[1]: Started Start Volumio Kiosk.
    Feb 11 21:51:54 volumio sudo[4353]: pam_unix(sudo:session): session closed for u
    ser root
    Feb 11 21:51:54 volumio volumio[4297]: info: CoreCommandRouter::Close All Modals
    sent
    Feb 11 21:51:54 volumio volumio[4297]: Express server listening on port 3000
    Feb 11 21:51:55 volumio volumio[4297]: Volumio Calling Home
    Feb 11 21:51:55 volumio volumio[4297]: info: CoreStateMachine::resetVolumioState
    Feb 11 21:51:55 volumio volumio[4297]: info: CoreStateMachine::getcurrentVolume
    Feb 11 21:51:55 volumio volumio[4297]: info: CoreCommandRouter::volumioRetrievev
    olume
    Feb 11 21:51:55 volumio volumio[4297]: info: CoreStateMachine::updateTrackBlock
    Feb 11 21:51:55 volumio volumio[4297]: info: CorePlayQueue::getTrackBlock
    Feb 11 21:51:56 volumio volumio[4297]: info: Setting Device type: Raspberry PI
    Feb 11 21:51:56 volumio volumio[4297]: { Error: Command failed: /usr/bin/aplay -
    -device=plughw:1,0 /volumio/app/startup.wav
    Feb 11 21:51:56 volumio volumio[4297]: aplay: main:722: audio open error: Device
    or resource busy
    Feb 11 21:51:56 volumio volumio[4297]: at ChildProcess.exithandler (child_proces
    s.js:204:12)
    Feb 11 21:51:56 volumio volumio[4297]: at emitTwo (events.js:106:13)
    Feb 11 21:51:56 volumio volumio[4297]: at ChildProcess.emit (events.js:191:7)
    Feb 11 21:51:56 volumio volumio[4297]: at maybeClose (internal/child_process.js:
    891:16)
    Feb 11 21:51:56 volumio volumio[4297]: at Socket. (internal/child_pro
    cess.js:342:11)
    Feb 11 21:51:56 volumio volumio[4297]: at emitOne (events.js:96:13)
    Feb 11 21:51:56 volumio volumio[4297]: at Socket.emit (events.js:188:7)
    Feb 11 21:51:56 volumio volumio[4297]: at Pipe._handle.close [as _onclose] (net.
    js:497:12)
    Feb 11 21:51:56 volumio volumio[4297]: killed: false,
    Feb 11 21:51:56 volumio volumio[4297]: code: 1,
    Feb 11 21:51:56 volumio volumio[4297]: signal: null,
    Feb 11 21:51:56 volumio volumio[4297]: cmd: ‘/usr/bin/aplay --device=plughw:1,0
    /volumio/app/startup.wav’ }
    Feb 11 21:51:56 volumio volumio[4297]: info: CoreCommandRouter::Close All Modals
    sent
    Feb 11 21:51:56 volumio volumio[4297]: info: Volumio Kiosk Started
    Feb 11 21:51:56 volumio volumio[4297]: info: Kiosk Started
    Feb 11 21:51:56 volumio volumio[4297]: info: MPD running with PID670 ,establishi
    ng connection
    Feb 11 21:51:56 volumio volumio[4297]: info: Volumio called home
    Feb 11 21:51:57 volumio volumio[4297]: info: VolumeController:: Volume=40 Mute =
    false
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreStateMachine::pushState
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreStateMachine::getState
    Feb 11 21:51:57 volumio volumio[4297]: info: CorePlayQueue::getTrack 0
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreCommandRouter::volumioPushState
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    volumiodiscovery , saveDeviceInfo
    Feb 11 21:51:57 volumio volumio[4297]: info: interfaceApi::pushState
    Feb 11 21:51:57 volumio volumio[4297]: info: Reloading queue from file
    Feb 11 21:51:57 volumio volumio[4297]: info: Pushing Favourites {“service”:“mpd”
    ,“uri”:"",“favourite”:false}
    Feb 11 21:51:57 volumio volumio[4297]: info: Cannot mount NAS NM10 at system boo
    t, trial number 2 ,retrying in 5 seconds
    Feb 11 21:51:57 volumio volumio[4297]: info: Cannot mount NAS Pop at system boot
    , trial number 2 ,retrying in 5 seconds
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreStateMachine::setRepeat null si
    ngle undefined
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreStateMachine::pushState
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreStateMachine::getState
    Feb 11 21:51:57 volumio volumio[4297]: info: CorePlayQueue::getTrack 0
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreCommandRouter::volumioPushState
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    volumiodiscovery , saveDeviceInfo
    Feb 11 21:51:57 volumio volumio[4297]: info: interfaceApi::pushState
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreStateMachine::setRandom null
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreStateMachine::pushState
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreStateMachine::getState
    Feb 11 21:51:57 volumio volumio[4297]: info: CorePlayQueue::getTrack 0
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreCommandRouter::volumioPushState
    Feb 11 21:51:57 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    volumiodiscovery , saveDeviceInfo
    Feb 11 21:51:57 volumio volumio[4297]: info: interfaceApi::pushState
    Feb 11 21:51:58 volumio volumio[4297]: info: Pushing Favourites {“service”:“mpd”
    ,“uri”:“mnt/NAS/Pop/ABC - The Lexicon Of Love/06 - The Look Of Love (Part One).f
    lac”,“favourite”:false}
    Feb 11 21:51:58 volumio volumio[4297]: info: Pushing Favourites {“service”:“mpd”
    ,“uri”:“mnt/NAS/Pop/ABC - The Lexicon Of Love/06 - The Look Of Love (Part One).f
    lac”,“favourite”:false}
    Feb 11 21:51:58 volumio sudo[4377]: volumio : TTY=unknown ; PWD=/ ; USER=root ;
    COMMAND=/bin/systemctl restart airplay
    Feb 11 21:51:58 volumio sudo[4377]: pam_unix(sudo:session): session opened for u
    ser root by (uid=0)
    Feb 11 21:51:58 volumio systemd[1]: Stopping ShairportSync AirTunes receiver…
    Feb 11 21:51:58 volumio systemd[1]: Starting ShairportSync AirTunes receiver…
    Feb 11 21:51:58 volumio systemd[1]: Started ShairportSync AirTunes receiver.
    Feb 11 21:51:58 volumio sudo[4377]: pam_unix(sudo:session): session closed for u
    ser root
    Feb 11 21:51:58 volumio shairport-sync[4383]: Successful Startup
    Feb 11 21:51:58 volumio volumio[4297]: Discovery: adding 49e22476-2257-42d5-ad2d
    -0677de42ecfe
    Feb 11 21:51:58 volumio volumio[4297]: info: mDNS: Found device spdifio
    Feb 11 21:51:58 volumio volumio[4297]: info: CoreCommandRouter::volumioGetState
    Feb 11 21:51:58 volumio volumio[4297]: info: CoreStateMachine::getState
    Feb 11 21:51:58 volumio volumio[4297]: info: CorePlayQueue::getTrack 0
    Feb 11 21:51:59 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    multiroom , getMultiroom
    Feb 11 21:51:59 volumio volumio[4297]: Plugin multiroom or method getMultiroom n
    ot found
    Feb 11 21:51:59 volumio volumio[4297]: info: Listing playlists
    Feb 11 21:51:59 volumio volumio-remote-updater[644]: [2018-02-11 21:51:59] [conn
    ect] Successful connection
    Feb 11 21:52:00 volumio volumio[4297]: info: [1518385920584] Shairport-Sync Star
    ted
    Feb 11 21:52:00 volumio volumio[4297]: Error adding Membership: Error: addMember
    ship EINVAL
    Feb 11 21:52:03 volumio volumio[4297]: info: Cannot mount NAS NM10 at system boo
    t, trial number 3 ,retrying in 5 seconds
    Feb 11 21:52:03 volumio volumio[4297]: info: Cannot mount NAS Pop at system boot
    , trial number 3 ,retrying in 5 seconds
    Feb 11 21:52:10 volumio volumio[4297]: info: Cannot mount NAS NM10 at system boo
    t, trial number 4 ,retrying in 5 seconds
    Feb 11 21:52:10 volumio volumio[4297]: info: Cannot mount NAS Pop at system boot
    , trial number 4 ,retrying in 5 seconds
    Feb 11 21:52:10 volumio volumio[4297]: Discovery: adding 0ede78f0-f977-4bfe-9697
    -f874e6b69709
    Feb 11 21:52:10 volumio volumio[4297]: info: mDNS: Found device Volumio
    Feb 11 21:52:11 volumio volumio[4297]: info: CoreCommandRouter::volumioGetState
    Feb 11 21:52:11 volumio volumio[4297]: info: CoreStateMachine::getState
    Feb 11 21:52:11 volumio volumio[4297]: info: CorePlayQueue::getTrack 0
    Feb 11 21:52:11 volumio sudo[4404]: volumio : TTY=unknown ; PWD=/ ; USER=root ;
    COMMAND=/bin/systemctl start upmpdcli.service
    Feb 11 21:52:11 volumio sudo[4404]: pam_unix(sudo:session): session opened for u
    ser root by (uid=0)
    Feb 11 21:52:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
    Feb 11 21:52:12 volumio sudo[4404]: pam_unix(sudo:session): session closed for u
    ser root
    Feb 11 21:52:12 volumio volumio[4297]: info: Upmpdcli Daemon Started
    Feb 11 21:52:13 volumio volumio[4297]: info: CoreCommandRouter::volumioGetState
    Feb 11 21:52:13 volumio volumio[4297]: info: CoreStateMachine::getState
    Feb 11 21:52:13 volumio volumio[4297]: info: CorePlayQueue::getTrack 0
    Feb 11 21:52:13 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    multiroom , getMultiroom
    Feb 11 21:52:13 volumio volumio[4297]: Plugin multiroom or method getMultiroom n
    ot found
    Feb 11 21:52:13 volumio volumio[4297]: info: Listing playlists
    Feb 11 21:52:14 volumio volumio-remote-updater[644]: [2018-02-11 21:52:14] [conn
    ect] Successful connection
    Feb 11 21:52:14 volumio volumio-remote-updater[644]: [2018-02-11 21:52:14] [conn
    ect] WebSocket Connection 127.0.0.1:3000 v-2 “WebSocket++/0.5.1” /socket.io/?EIO
    =4&transport=websocket&t=1518385934 101
    Feb 11 21:52:15 volumio volumio[4297]: info: Cannot mount NAS at system boot, tr
    ial number 4 ,stopping
    Feb 11 21:52:15 volumio volumio[4297]: info: Cannot mount NAS at system boot, tr
    ial number 4 ,stopping
    Feb 11 21:52:19 volumio volumio[4297]: info: CoreCommandRouter::volumioGetState
    Feb 11 21:52:19 volumio volumio[4297]: info: CoreStateMachine::getState
    Feb 11 21:52:19 volumio volumio[4297]: info: CorePlayQueue::getTrack 0
    Feb 11 21:52:19 volumio volumio[4297]: info: CoreCommandRouter::executeOnPlugin:
    multiroom , getMultiroom
    Feb 11 21:52:19 volumio volumio[4297]: Plugin multiroom or method getMultiroom n
    ot found
    Feb 11 21:52:19 volumio volumio[4297]: info: Listing playlists
    Feb 11 21:52:19 volumio volumio[4297]: info: CoreCommandRouter::volumioGetQueue
    Feb 11 21:52:19 volumio volumio[4297]: info: CoreStateMachine::getQueue
    Feb 11 21:52:19 volumio volumio[4297]: info: CorePlayQueue::getQueue
    Feb 11 21:52:19 volumio volumio[4297]: info: CoreCommandRouter::volumioGetBrowse
    Sources
    volumio@volumio:~$
    volumio@volumio:~$ ls -l logfile
    -rw-r–r-- 1 volumio volumio 44520 Feb 11 21:52 logfile
    volumio@volumio:~$
9

Thanks, now I know where the problem is, looking into it now

10

Think I’ve solved it.
Could you, by any chance, do

volumio pull

and report if the crash is still there?

(when new version will be released, you would be better to reflash)

Thanks!

11

Pulled. Appears the patch fixed the problem.

12

New image released :wink:

13

Thanks Michelangelo… I thought my router was broken… :slight_smile:

14

No, ehehe it was Volumio.
Could you confirm this update works for you as well?

15

Yep… this evening… you can count on it :slight_smile:

16

Hi michelangelo,

I do confirm that your latest release fixed the issue.

Thanks again for your job !

17

RPI3, Kali, Piano 2.1…
Problem solved… no more buffering and all plays well.
During music inventory the GUI is less responsive, sometime freeze browsing over my music archive…
But, after inventory… all works well :slight_smile:

18

Very good, happy to hear that