Volumio on Raspi3 goes into Zombie state

I’m having an issue with my Volumio 3 running on a Raspberry Pi 3 that’s on 24/7 (workshop speaker system).
After a day or two it drops of the network completely, and a hard reset is the only thing that will bring it back. After a few resets it goes into “emergency boot mode” and there’s no going back after that except reflashing and trying again…

This to me looks like a non adequate Power Supply. Please use a quality Power supply capable of real 5V (under load) and 2A and you’ll see that your problem is solved.

Let me know

I’m currently using a laptop supply (20VDC, some amps) to power the Hifiberry AMP2, which is powering the Raspberry, I’ll try using a different/larger supply and see if that helps things!

Well, no wonder, the Hifiberry is rated at 60W, and the supply is only 2A, so I’m an amp short just to drive the amplifier at full load. The weird thing is that it goes Zombie on me when it’s not playing music, at which time you would expect the Raspberry to be able to survive on a 40W supply…

Well, now I have a 6,5A 20VDC supply on the Hifiberry feeding things, so power shouldn’t be a problem! :slight_smile: Well see how things progress!

Okay, so the problem isn’t the PSU, if it isn’t a problem with the Hifiberry AMP2 itself, as it behaves the same way with the new 3 times larger PSU. Any other ideas? Are there any internal logs that might be helpful?

Are you able to access the RPi via ssh or directly with a keyboard & monitor? If so, you could monitor the system log until the problem occurs (at the command line run “sudo journalctl -f”).

Will do so, might take a week or two before I’m at the shop next time, but the problem is fairly recurring, so I’ll post the logs as soon as I can get them!
SSH is by default tuned of in Volumio if I remember correctly, what’s the easiest way to get it working?

volumio.github.io/docs/User_Manual/SSH.html

Hi,

Here’s the log output right after a hard reset, until the device becomes unresponsive.

Cheers,
Stuggi

[code]login as: volumio
volumio@192.168.2.105’s password:
±-------------------------------------------------------------------+
¦ • MobaXterm 10.2 • ¦
¦ (SSH client, X-server and networking tools) ¦
¦ ¦
¦ ? SSH session to volumio@192.168.2.105 ¦
¦ • SSH compression : ? ¦
¦ • SSH-browser : ? ¦
¦ • X11-forwarding : ? (disabled or not supported by server) ¦
¦ • DISPLAY : 192.168.2.101:0.0 ¦
¦ ¦
¦ ? For more info, ctrl+click on help or visit our website ¦
±-------------------------------------------------------------------+

                   ___
                  /\_ \                        __
     __  __    ___\//\ \    __  __    ___ ___ /\_\    ___
    /\ \/\ \  / __`\\ \ \  /\ \/\ \ /' __` __`\/\ \  / __`\
    \ \ \_/ |/\ \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 Sat 2017-11-04 16:19:11 UTC. –
Nov 24 19:20:49 volumio systemd[1]: Started User Manager for UID 1000.
Nov 24 19:20:49 volumio sshd[6101]: Accepted password for volumio from 192.168.2.101 port 54199 ssh2
Nov 24 19:20:49 volumio sshd[6101]: pam_unix(sshd:session): session opened for user volumio by (uid=0)
Nov 24 19:20:49 volumio systemd[1]: Starting Session c2 of user volumio.
Nov 24 19:20:49 volumio systemd-logind[574]: New session c2 of user volumio.
Nov 24 19:20:49 volumio systemd[1]: Started Session c2 of user volumio.
Nov 24 19:20:52 volumio sshd[6061]: lastlog_openseek: Couldn’t stat /var/log/lastlog: No such file or directory
Nov 24 19:20:52 volumio sshd[6061]: lastlog_openseek: Couldn’t stat /var/log/lastlog: No such file or directory
Nov 24 19:21:16 volumio sudo[6307]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Nov 24 19:21:16 volumio sudo[6307]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Nov 24 19:23:16 volumio systemd[1]: Starting Cleanup of Temporary Directories…
Nov 24 19:23:16 volumio systemd[1]: Started Cleanup of Temporary Directories.
Nov 24 19:26:24 volumio volumio[1017]: 2017-11-24 19:26:24 [SPTF] 19:26:24.786 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 19:28:40 volumio volumio[1017]: 2017-11-24 19:28:40 [SPTF] 19:28:40.442 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 19:31:44 volumio volumio[955]: info:
Nov 24 19:31:44 volumio volumio[955]: [1511551904512] ---------------------------- Client requests Volumio play
Nov 24 19:31:44 volumio volumio[955]: info: CoreCommandRouter::volumioPlay
Nov 24 19:31:44 volumio volumio[955]: UNSET VOLATILE
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904519] CoreStateMachine::play index undefined
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904522] CoreStateMachine::setConsumeUpdateService undefined
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904535] CorePlayQueue::getTrack 0
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904537] CoreStateMachine::startPlaybackTimer
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904538] CorePlayQueue::getTrack 0
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904542] ControllerSpop::clearAddPlayTrack
Nov 24 19:31:44 volumio volumio[955]: info: {“uri”:“spotify:track:3rmhjtyrVrjyuZnpgMwT8A”,“service”:“spop”,“type”:“song”,“name”:“Vill Du Bli Munk?”,“title”:“Vill Du Bli Munk?”,“artist”:“Jazzkamikaze”,“album”:“Mission I”,“duration”:334,“albumart”:"/albumart?web=Jazzkamikaze/Mission%20I/large&path=",“samplerate”:“320Kbps”,“bitdepth”:“16 bit”,“trackType”:“spotify”}
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904546] ControllerSpop::sendSpopCommand
Nov 24 19:31:44 volumio volumio[955]: info: ADDING DEFER FOR COMMAND uplay
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904567] ------------------------------ 55ms
Nov 24 19:31:44 volumio volumio[955]: info: STATUS
Nov 24 19:31:44 volumio volumio[955]: info: {
Nov 24 19:31:44 volumio volumio[955]: “status” : “playing”,
Nov 24 19:31:44 volumio volumio[955]: “repeat” : false,
Nov 24 19:31:44 volumio volumio[955]: “shuffle” : false,
Nov 24 19:31:44 volumio volumio[955]: “total_tracks” : 1,
Nov 24 19:31:44 volumio volumio[955]: “current_track” : 1,
Nov 24 19:31:44 volumio volumio[955]: “artist” : “Jazzkamikaze”,
Nov 24 19:31:44 volumio volumio[955]: “title” : “Vill Du Bli Munk?”,
Nov 24 19:31:44 volumio volumio[955]: “album” : “Mission I”,
Nov 24 19:31:44 volumio volumio[955]: “duration” : 334000,
Nov 24 19:31:44 volumio volumio[955]: “position” : 0,
Nov 24 19:31:44 volumio volumio[955]: “uri” : “spotify:track:3rmhjtyrVrjyuZnpgMwT8A”,
Nov 24 19:31:44 volumio volumio[955]: “popularity” : 12
Nov 24 19:31:44 volumio volumio[955]: }
Nov 24 19:31:44 volumio volumio[955]: info:
Nov 24 19:31:44 volumio volumio[955]: [1511551904599] ---------------------------- Spop announces state update
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904604] ControllerSpop::parseState
Nov 24 19:31:44 volumio volumio[955]: { status: ‘playing’,
Nov 24 19:31:44 volumio volumio[955]: repeat: false,
Nov 24 19:31:44 volumio volumio[955]: shuffle: false,
Nov 24 19:31:44 volumio volumio[955]: total_tracks: 1,
Nov 24 19:31:44 volumio volumio[955]: current_track: 1,
Nov 24 19:31:44 volumio volumio[955]: artist: ‘Jazzkamikaze’,
Nov 24 19:31:44 volumio volumio[955]: title: ‘Vill Du Bli Munk?’,
Nov 24 19:31:44 volumio volumio[955]: album: ‘Mission I’,
Nov 24 19:31:44 volumio volumio[955]: duration: 334000,
Nov 24 19:31:44 volumio volumio[955]: position: 0,
Nov 24 19:31:44 volumio volumio[955]: uri: ‘spotify:track:3rmhjtyrVrjyuZnpgMwT8A’,
Nov 24 19:31:44 volumio volumio[955]: popularity: 12 }
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904624] ControllerSpop::pushState
Nov 24 19:31:44 volumio volumio[955]: info: CoreCommandRouter::servicePushState
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904636] CoreStateMachine::syncState
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904638] CorePlayQueue::getTrack 0
Nov 24 19:31:44 volumio volumio[955]: info: STATE SERVICE {“status”:“play”,“position”:0,“seek”:0,“duration”:334,“samplerate”:“320Kbps”,“bitdepth”:null,“channels”:null,“artist”:“Jazzkamikaze”,“title”:“Vill Du Bli Munk?”,“album”:“Mission I”}
Nov 24 19:31:44 volumio volumio[955]: info: CURRENT POSITION 0
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904651] CoreStateMachine::syncState stateService play
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904653] CoreStateMachine::syncState currentStatus stop
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904654] CoreStateMachine::pushState
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904656] CoreStateMachine::getState
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904657] CorePlayQueue::getTrack 0
Nov 24 19:31:44 volumio volumio[955]: info: CoreCommandRouter::volumioPushState
Nov 24 19:31:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904662] interfaceApi::pushState
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904663] InterfaceWebUI::pushState
Nov 24 19:31:44 volumio volumio[955]: info: [1511551904720] ------------------------------ 125ms
Nov 24 19:31:44 volumio volumio[955]: info: FIRST BRANCH
Nov 24 19:31:44 volumio volumio[955]: info: BEFORE: SPOP HAS 1 PROMISE IN STACK
Nov 24 19:31:44 volumio volumio[955]: info: AFTER: SPOP HAS 0 PROMISE IN STACK
Nov 24 19:31:44 volumio volumio[955]: info: Pushing Favourites {“service”:“spop”,“uri”:“spotify:track:3rmhjtyrVrjyuZnpgMwT8A”,“favourite”:false}
Nov 24 19:31:46 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 24 19:31:48 volumio volumio[1017]: 2017-11-24 19:31:48 [SPTF] 19:31:48.682 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:31:51 volumio volumio[1017]: 2017-11-24 19:31:51 [SPTF] 19:31:51.067 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:31:59 volumio volumio[1017]: 2017-11-24 19:31:59 [SPTF] 19:31:59.786 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:32:18 volumio volumio[1017]: 2017-11-24 19:32:18 [SPTF] 19:32:18.441 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:32:26 volumio volumio[1017]: 2017-11-24 19:32:26 [SPTF] 19:32:26.657 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:32:39 volumio volumio[1017]: 2017-11-24 19:32:39 [SPTF] 19:32:39.730 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:32:53 volumio volumio[1017]: 2017-11-24 19:32:53 [SPTF] 19:32:53.551 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:33:09 volumio volumio[1017]: 2017-11-24 19:33:09 [SPTF] 19:33:09.727 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:33:21 volumio volumio[1017]: 2017-11-24 19:33:21 [SPTF] 19:33:21.340 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:33:35 volumio volumio[1017]: 2017-11-24 19:33:35 [SPTF] 19:33:35.672 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:33:48 volumio volumio[1017]: 2017-11-24 19:33:48 [SPTF] 19:33:48.681 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:34:01 volumio volumio[1017]: 2017-11-24 19:34:01 [SPTF] 19:34:01.703 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:34:15 volumio volumio[1017]: 2017-11-24 19:34:15 [SPTF] 19:34:15.754 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:34:29 volumio volumio[1017]: 2017-11-24 19:34:29 [SPTF] 19:34:29.847 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:34:42 volumio volumio[1017]: 2017-11-24 19:34:42 [SPTF] 19:34:42.443 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:34:56 volumio volumio[1017]: 2017-11-24 19:34:56 [SPTF] 19:34:56.212 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:35:09 volumio volumio[1017]: 2017-11-24 19:35:09 [SPTF] 19:35:09.439 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:35:23 volumio volumio[1017]: 2017-11-24 19:35:23 [SPTF] 19:35:23.109 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:35:37 volumio volumio[1017]: 2017-11-24 19:35:37 [SPTF] 19:35:37.813 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:35:51 volumio volumio[1017]: 2017-11-24 19:35:51 [SPTF] 19:35:51.558 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:36:06 volumio volumio[1017]: 2017-11-24 19:36:06 [SPTF] 19:36:06.872 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:36:19 volumio volumio[1017]: 2017-11-24 19:36:19 [SPTF] 19:36:19.106 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:36:32 volumio volumio[1017]: 2017-11-24 19:36:32 [SPTF] 19:36:32.551 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:36:45 volumio volumio[1017]: 2017-11-24 19:36:45 [SPTF] 19:36:45.620 I [file_streamer_simple.cpp:769] Request for file c3589341789e830d57d4ad814aa6af47fb4dd983 complete (code: 0)
Nov 24 19:37:13 volumio volumio[955]: info: [1511552233568] CorePlayQueue::getTrack 0
Nov 24 19:37:13 volumio volumio[955]: info: [1511552233573] CorePlayQueue::getTrack 116
Nov 24 19:37:13 volumio volumio[955]: info: Prefetching next song
Nov 24 19:37:19 volumio volumio[1017]: 2017-11-24 19:37:19 [SPTF] 19:37:19.972 I [social_presence.cpp:135] Presence: A track was played
Nov 24 19:37:19 volumio volumio[955]: info: STATUS
Nov 24 19:37:19 volumio volumio[955]: info: {
Nov 24 19:37:19 volumio volumio[955]: “status” : “stopped”,
Nov 24 19:37:19 volumio volumio[955]: “repeat” : false,
Nov 24 19:37:19 volumio volumio[955]: “shuffle” : false,
Nov 24 19:37:19 volumio volumio[955]: “total_tracks” : 1
Nov 24 19:37:19 volumio volumio[955]: }
Nov 24 19:37:19 volumio volumio[955]: info:
Nov 24 19:37:19 volumio volumio[955]: [1511552239975] ---------------------------- Spop announces state update
Nov 24 19:37:19 volumio volumio[955]: info: [1511552239976] ControllerSpop::parseState
Nov 24 19:37:19 volumio volumio[955]: { status: ‘stopped’,
Nov 24 19:37:19 volumio volumio[955]: repeat: false,
Nov 24 19:37:19 volumio volumio[955]: shuffle: false,
Nov 24 19:37:19 volumio volumio[955]: total_tracks: 1 }
Nov 24 19:37:20 volumio volumio[955]: info: [1511552239981] ControllerSpop::pushState
Nov 24 19:37:20 volumio volumio[955]: info: CoreCommandRouter::servicePushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552239984] CoreStateMachine::syncState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552239986] CorePlayQueue::getTrack 0
Nov 24 19:37:20 volumio volumio[955]: info: STATE SERVICE {“status”:“stop”,“position”:null,“seek”:null,“duration”:null,“samplerate”:“320Kbps”,“bitdepth”:null,“channels”:null}
Nov 24 19:37:20 volumio volumio[955]: info: CURRENT POSITION 0
Nov 24 19:37:20 volumio volumio[955]: info: [1511552239993] CoreStateMachine::syncState stateService stop
Nov 24 19:37:20 volumio volumio[955]: info: [1511552239994] CoreStateMachine::syncState currentStatus play
Nov 24 19:37:20 volumio volumio[955]: info: CURRENT POSITION 0
Nov 24 19:37:20 volumio volumio[955]: info: CURRENT POSITION 116
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240002] CoreStateMachine::play index undefined
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240003] CoreStateMachine::setConsumeUpdateService undefined
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240006] CoreStateMachine::pushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240008] CoreStateMachine::getState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240009] CorePlayQueue::getTrack 116
Nov 24 19:37:20 volumio volumio[955]: info: CoreCommandRouter::volumioPushState
Nov 24 19:37:20 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240017] interfaceApi::pushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240019] InterfaceWebUI::pushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240056] CorePlayQueue::getTrack 116
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240058] CoreStateMachine::startPlaybackTimer
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240058] CorePlayQueue::getTrack 116
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240061] ControllerSpop::clearAddPlayTrack
Nov 24 19:37:20 volumio volumio[955]: info: {“uri”:“spotify:track:3SCRNG1UdHZVo5ZXeBegDV”,“service”:“spop”,“type”:“song”,“name”:“Easy (Switch Screens) [feat. Lorde]”,“title”:“Easy (Switch Screens) [feat. Lorde]”,“artist”:“Son Lux, Lorde”,“album”:“Alternate Worlds”,“duration”:263,“albumart”:"/albumart?web=Son%20Lux%2C%20Lorde/Alternate%20Worlds/large&path=",“samplerate”:“320Kbps”,“bitdepth”:“16 bit”,“trackType”:“spotify”}
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240065] ControllerSpop::sendSpopCommand
Nov 24 19:37:20 volumio volumio[955]: info: ADDING DEFER FOR COMMAND uplay
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240074] ------------------------------ 101ms
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240076] CoreStateMachine::pushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240078] CoreStateMachine::getState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240079] CorePlayQueue::getTrack 116
Nov 24 19:37:20 volumio volumio[955]: info: CoreCommandRouter::volumioPushState
Nov 24 19:37:20 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240098] interfaceApi::pushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240100] InterfaceWebUI::pushState
Nov 24 19:37:20 volumio volumio[955]: info: STATUS
Nov 24 19:37:20 volumio volumio[955]: info: {
Nov 24 19:37:20 volumio volumio[955]: “status” : “playing”,
Nov 24 19:37:20 volumio volumio[955]: “repeat” : false,
Nov 24 19:37:20 volumio volumio[955]: “shuffle” : false,
Nov 24 19:37:20 volumio volumio[955]: “total_tracks” : 1,
Nov 24 19:37:20 volumio volumio[955]: “current_track” : 1,
Nov 24 19:37:20 volumio volumio[955]: “artist” : “Son Lux, Lorde”,
Nov 24 19:37:20 volumio volumio[955]: “title” : “Easy (Switch Screens) [feat. Lorde]”,
Nov 24 19:37:20 volumio volumio[955]: “album” : “Alternate Worlds”,
Nov 24 19:37:20 volumio volumio[955]: “duration” : 263000,
Nov 24 19:37:20 volumio volumio[955]: “position” : 0,
Nov 24 19:37:20 volumio volumio[955]: “uri” : “spotify:track:3SCRNG1UdHZVo5ZXeBegDV”,
Nov 24 19:37:20 volumio volumio[955]: “popularity” : 52
Nov 24 19:37:20 volumio volumio[955]: }
Nov 24 19:37:20 volumio volumio[955]: info:
Nov 24 19:37:20 volumio volumio[955]: [1511552240142] ---------------------------- Spop announces state update
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240143] ControllerSpop::parseState
Nov 24 19:37:20 volumio volumio[955]: { status: ‘playing’,
Nov 24 19:37:20 volumio volumio[955]: repeat: false,
Nov 24 19:37:20 volumio volumio[955]: shuffle: false,
Nov 24 19:37:20 volumio volumio[955]: total_tracks: 1,
Nov 24 19:37:20 volumio volumio[955]: current_track: 1,
Nov 24 19:37:20 volumio volumio[955]: artist: ‘Son Lux, Lorde’,
Nov 24 19:37:20 volumio volumio[955]: title: ‘Easy (Switch Screens) [feat. Lorde]’,
Nov 24 19:37:20 volumio volumio[955]: album: ‘Alternate Worlds’,
Nov 24 19:37:20 volumio volumio[955]: duration: 263000,
Nov 24 19:37:20 volumio volumio[955]: position: 0,
Nov 24 19:37:20 volumio volumio[955]: uri: ‘spotify:track:3SCRNG1UdHZVo5ZXeBegDV’,
Nov 24 19:37:20 volumio volumio[955]: popularity: 52 }
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240148] ControllerSpop::pushState
Nov 24 19:37:20 volumio volumio[955]: info: CoreCommandRouter::servicePushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240150] CoreStateMachine::syncState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240151] CorePlayQueue::getTrack 116
Nov 24 19:37:20 volumio volumio[955]: info: STATE SERVICE {“status”:“play”,“position”:0,“seek”:0,“duration”:263,“samplerate”:“320Kbps”,“bitdepth”:null,“channels”:null,“artist”:“Son Lux, Lorde”,“title”:“Easy (Switch Screens) [feat. Lorde]”,“album”:“Alternate Worlds”}
Nov 24 19:37:20 volumio volumio[955]: info: CURRENT POSITION 116
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240156] CoreStateMachine::syncState stateService play
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240157] CoreStateMachine::syncState currentStatus stop
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240159] CoreStateMachine::pushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240161] CoreStateMachine::getState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240162] CorePlayQueue::getTrack 116
Nov 24 19:37:20 volumio volumio[955]: info: CoreCommandRouter::volumioPushState
Nov 24 19:37:20 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240167] interfaceApi::pushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240168] InterfaceWebUI::pushState
Nov 24 19:37:20 volumio volumio[955]: info: [1511552240215] ------------------------------ 75ms
Nov 24 19:37:20 volumio volumio[955]: info: Pushing Favourites {“service”:“spop”,“uri”:“spotify:track:3SCRNG1UdHZVo5ZXeBegDV”,“favourite”:false}
Nov 24 19:37:20 volumio volumio[955]: info: Pushing Favourites {“service”:“spop”,“uri”:“spotify:track:3SCRNG1UdHZVo5ZXeBegDV”,“favourite”:false}
Nov 24 19:37:20 volumio volumio[955]: info: Pushing Favourites {“service”:“spop”,“uri”:“spotify:track:3SCRNG1UdHZVo5ZXeBegDV”,“favourite”:false}
Nov 24 19:37:20 volumio volumio[955]: info: FIRST BRANCH
Nov 24 19:37:20 volumio volumio[955]: info: BEFORE: SPOP HAS 1 PROMISE IN STACK
Nov 24 19:37:20 volumio volumio[955]: info: AFTER: SPOP HAS 0 PROMISE IN STACK
Nov 24 19:37:22 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 24 19:37:25 volumio volumio[1017]: 2017-11-24 19:37:25 [SPTF] 19:37:25.232 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:37:26 volumio volumio[1017]: 2017-11-24 19:37:26 [SPTF] 19:37:26.944 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:37:36 volumio volumio[1017]: 2017-11-24 19:37:36 [SPTF] 19:37:36.818 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:37:49 volumio volumio[1017]: 2017-11-24 19:37:49 [SPTF] 19:37:49.107 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:38:00 volumio volumio[1017]: 2017-11-24 19:38:00 [SPTF] 19:38:00.003 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:38:13 volumio volumio[1017]: 2017-11-24 19:38:13 [SPTF] 19:38:13.302 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:38:30 volumio volumio[1017]: 2017-11-24 19:38:30 [SPTF] 19:38:30.216 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:38:38 volumio volumio[1017]: 2017-11-24 19:38:38 [SPTF] 19:38:38.361 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:38:50 volumio volumio[1017]: 2017-11-24 19:38:50 [SPTF] 19:38:50.356 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:39:01 volumio volumio[1017]: 2017-11-24 19:39:01 [SPTF] 19:39:01.446 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:39:13 volumio volumio[1017]: 2017-11-24 19:39:13 [SPTF] 19:39:13.601 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:39:25 volumio volumio[1017]: 2017-11-24 19:39:25 [SPTF] 19:39:25.689 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:39:38 volumio volumio[1017]: 2017-11-24 19:39:38 [SPTF] 19:39:38.357 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:39:51 volumio volumio[1017]: 2017-11-24 19:39:51 [SPTF] 19:39:51.348 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:40:03 volumio volumio[1017]: 2017-11-24 19:40:03 [SPTF] 19:40:03.499 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:40:12 volumio volumio[1017]: 2017-11-24 19:40:12 [SPTF] 19:40:12.002 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 19:40:16 volumio volumio[1017]: 2017-11-24 19:40:16 [SPTF] 19:40:16.337 I [file_streamer_simple.cpp:769] Request for file 87f78a912faf7a57950e35e1b7d6791b97612189 complete (code: 0)
Nov 24 19:40:23 volumio volumio[955]: info:
Nov 24 19:40:23 volumio volumio[955]: [1511552423367] ---------------------------- Client requests Volumio pause
Nov 24 19:40:23 volumio volumio[955]: info: CoreCommandRouter::volumioPause
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423370] CoreStateMachine::pause
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423372] CoreStateMachine::stPlaybackTimer
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423374] CoreStateMachine::servicePause
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423376] CorePlayQueue::getTrack 116
Nov 24 19:40:23 volumio volumio[955]: info: CoreCommandRouter::servicePause
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423380] ControllerSpop::pause
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423392] ControllerSpop::sendSpopCommand
Nov 24 19:40:23 volumio volumio[955]: info: ADDING DEFER FOR COMMAND toggle
Nov 24 19:40:23 volumio volumio[955]: info: STATUS
Nov 24 19:40:23 volumio volumio[955]: info: {
Nov 24 19:40:23 volumio volumio[955]: “status” : “paused”,
Nov 24 19:40:23 volumio volumio[955]: “repeat” : false,
Nov 24 19:40:23 volumio volumio[955]: “shuffle” : false,
Nov 24 19:40:23 volumio volumio[955]: “total_tracks” : 1,
Nov 24 19:40:23 volumio volumio[955]: “current_track” : 1,
Nov 24 19:40:23 volumio volumio[955]: “artist” : “Son Lux, Lorde”,
Nov 24 19:40:23 volumio volumio[955]: “title” : “Easy (Switch Screens) [feat. Lorde]”,
Nov 24 19:40:23 volumio volumio[955]: “album” : “Alternate Worlds”,
Nov 24 19:40:23 volumio volumio[955]: “duration” : 263000,
Nov 24 19:40:23 volumio volumio[955]: “position” : 181.16200000000001,
Nov 24 19:40:23 volumio volumio[955]: “uri” : “spotify:track:3SCRNG1UdHZVo5ZXeBegDV”,
Nov 24 19:40:23 volumio volumio[955]: “popularity” : 52
Nov 24 19:40:23 volumio volumio[955]: }
Nov 24 19:40:23 volumio volumio[955]: info:
Nov 24 19:40:23 volumio volumio[955]: [1511552423804] ---------------------------- Spop announces state update
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423805] ControllerSpop::parseState
Nov 24 19:40:23 volumio volumio[955]: { status: ‘paused’,
Nov 24 19:40:23 volumio volumio[955]: repeat: false,
Nov 24 19:40:23 volumio volumio[955]: shuffle: false,
Nov 24 19:40:23 volumio volumio[955]: total_tracks: 1,
Nov 24 19:40:23 volumio volumio[955]: current_track: 1,
Nov 24 19:40:23 volumio volumio[955]: artist: ‘Son Lux, Lorde’,
Nov 24 19:40:23 volumio volumio[955]: title: ‘Easy (Switch Screens) [feat. Lorde]’,
Nov 24 19:40:23 volumio volumio[955]: album: ‘Alternate Worlds’,
Nov 24 19:40:23 volumio volumio[955]: duration: 263000,
Nov 24 19:40:23 volumio volumio[955]: position: 181.162,
Nov 24 19:40:23 volumio volumio[955]: uri: ‘spotify:track:3SCRNG1UdHZVo5ZXeBegDV’,
Nov 24 19:40:23 volumio volumio[955]: popularity: 52 }
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423811] ControllerSpop::pushState
Nov 24 19:40:23 volumio volumio[955]: info: CoreCommandRouter::servicePushState
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423813] CoreStateMachine::syncState
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423814] CorePlayQueue::getTrack 116
Nov 24 19:40:23 volumio volumio[955]: info: STATE SERVICE {“status”:“pause”,“position”:0,“seek”:181162,“duration”:263,“samplerate”:“320Kbps”,“bitdepth”:null,“channels”:null,“artist”:“Son Lux, Lorde”,“title”:“Easy (Switch Screens) [feat. Lorde]”,“album”:“Alternate Worlds”}
Nov 24 19:40:23 volumio volumio[955]: info: CURRENT POSITION 116
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423817] CoreStateMachine::syncState stateService pause
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423818] CoreStateMachine::syncState currentStatus pause
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423818] CoreStateMachine::pushState
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423821] CoreStateMachine::getState
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423823] CorePlayQueue::getTrack 116
Nov 24 19:40:23 volumio volumio[955]: info: CoreCommandRouter::volumioPushState
Nov 24 19:40:23 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423827] interfaceApi::pushState
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423828] InterfaceWebUI::pushState
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423865] CoreStateMachine::stPlaybackTimer
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423877] ------------------------------ 77ms
Nov 24 19:40:23 volumio volumio[955]: info: FIRST BRANCH
Nov 24 19:40:23 volumio volumio[955]: info: BEFORE: SPOP HAS 1 PROMISE IN STACK
Nov 24 19:40:23 volumio volumio[955]: info: AFTER: SPOP HAS 0 PROMISE IN STACK
Nov 24 19:40:23 volumio volumio[955]: info: [1511552423884] ------------------------------ 517ms
Nov 24 19:40:23 volumio volumio[955]: info: Pushing Favourites {“service”:“spop”,“uri”:“spotify:track:3SCRNG1UdHZVo5ZXeBegDV”,“favourite”:false}
Nov 24 19:45:25 volumio volumio[1017]: 2017-11-24 19:45:25 [SPTF] 19:45:25.222 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 20:09:34 volumio volumio[1017]: 2017-11-24 20:09:34 [SPTF] 20:09:34.871 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 20:15:49 volumio systemd[6102]: Time has been changed
Nov 24 20:15:49 volumio systemd[1]: Time has been changed
Nov 24 20:16:30 volumio volumio[1017]: 2017-11-24 20:16:30 [SPTF] 20:16:30.002 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 20:39:18 volumio volumio[1017]: 2017-11-24 20:39:18 [SPTF] 20:39:18.538 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 20:47:08 volumio volumio[1017]: 2017-11-24 20:47:08 [SPTF] 20:47:08.739 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 21:11:01 volumio volumio[1017]: 2017-11-24 21:11:01 [SPTF] 21:11:01.019 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 21:17:37 volumio volumio[1017]: 2017-11-24 21:17:37 [SPTF] 21:17:37.420 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 21:45:20 volumio volumio[1017]: 2017-11-24 21:45:20 [SPTF] 21:45:20.352 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 21:45:30 volumio volumio[1017]: 2017-11-24 21:45:30 [SPTF] 21:45:30.453 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 22:08:26 volumio volumio[1017]: 2017-11-24 22:08:26 [SPTF] 22:08:26.025 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 22:11:00 volumio volumio[1017]: 2017-11-24 22:11:00 [SPTF] 22:11:00.116 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 22:38:20 volumio volumio[1017]: 2017-11-24 22:38:20 [SPTF] 22:38:20.093 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 22:44:20 volumio volumio[1017]: 2017-11-24 22:44:20 [SPTF] 22:44:20.506 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 23:07:21 volumio volumio[1017]: 2017-11-24 23:07:21 [SPTF] 23:07:21.228 E [ap:4210] ChannelError(0, 1, playlist)
Nov 24 23:09:14 volumio volumio[1017]: 2017-11-24 23:09:14 [SPTF] 23:09:14.830 E [ap:4210] ChannelError(0, 1, playlist)


Session stopped
- Press to exit tab
- Press R to restart session
- Press S to save terminal output to file

Network error: Software caused connection abort
[/code]

I reran the same thing twice, and still the same result, the log files are almost identical.

Someone, please help? :slight_smile: