-- Logs begin at Sun 2025-04-27 21:50:50 KST, end at Sun 2025-04-27 21:53:13 KST. -- Apr 27 21:52:00 volumio_raspi3 sudo[1939]: pam_unix(sudo:session): session closed for user root Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: Cannot mount NAS volume(sdb1) at system boot, trial number 3 ,retrying in 5 seconds Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:00 volumio_raspi3 sudo[1943]: pam_unix(sudo:session): session closed for user root Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: Cannot mount NAS volume(sda1) at system boot, trial number 3 ,retrying in 5 seconds Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:05 volumio_raspi3 sudo[2099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=newmafi,password=newcurfew,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //ARCHER_C3200USB/volume(sdb1) /mnt/NAS/volume(sdb1) Apr 27 21:52:05 volumio_raspi3 sudo[2099]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:52:05 volumio_raspi3 sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //ARCHER_C3200USB/volume(sda1) /mnt/NAS/volume(sda1) Apr 27 21:52:05 volumio_raspi3 sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:05 volumio_raspi3 sudo[2099]: pam_unix(sudo:session): session closed for user root Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: Cannot mount NAS volume(sdb1) at system boot, trial number 4 ,retrying in 5 seconds Apr 27 21:52:05 volumio_raspi3 sudo[2107]: pam_unix(sudo:session): session closed for user root Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: Cannot mount NAS volume(sda1) at system boot, trial number 4 ,retrying in 5 seconds Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:07 volumio_raspi3 volumio[1228]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=0f6b61fab940faa767847f04305420b4" http://updates.volumio.org:7070/downloader-v1/track-device Apr 27 21:52:07 volumio_raspi3 volumio[1228]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 27 21:52:07 volumio_raspi3 volumio[1228]: Dload Upload Total Spent Left Speed Apr 27 21:52:07 volumio_raspi3 volumio[1228]: [235B blob data] Apr 27 21:52:07 volumio_raspi3 volumio[1228]: retrying in 5 seconds, trial 1 Apr 27 21:52:07 volumio_raspi3 volumio[1228]: Volumio Calling Home Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:10 volumio_raspi3 sudo[2254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=newmafi,password=newcurfew,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //ARCHER_C3200USB/volume(sdb1) /mnt/NAS/volume(sdb1) Apr 27 21:52:10 volumio_raspi3 sudo[2254]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:52:10 volumio_raspi3 sudo[2262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //ARCHER_C3200USB/volume(sda1) /mnt/NAS/volume(sda1) Apr 27 21:52:10 volumio_raspi3 sudo[2262]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:10 volumio_raspi3 sudo[2254]: pam_unix(sudo:session): session closed for user root Apr 27 21:52:10 volumio_raspi3 volumio[1228]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 21:52:11 volumio_raspi3 sudo[2262]: pam_unix(sudo:session): session closed for user root Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::ClearQueue Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::stop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 21:52:11 volumio_raspi3 volumio[1228]: UNSET VOLATILE Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::stPlaybackTimer Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::updateTrackBlock Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrackBlock Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::serviceStop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 7 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::serviceStop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ControllerMpd::stop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::clearPlayQueue Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::saveQueue Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushQueue Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::addQueueItems Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::addQueueItems Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Adding Item to queue: music-library/INTERNAL/Test/2L-056_03_stereo_DSD128.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Exploding uri music-library/INTERNAL/Test/2L-056_03_stereo_DSD128.dsf in service mpd Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Adding Item to queue: music-library/INTERNAL/Test/2L-056_03_stereo_DSD256.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Exploding uri music-library/INTERNAL/Test/2L-056_03_stereo_DSD256.dsf in service mpd Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Adding Item to queue: music-library/INTERNAL/Test/2L-056_03_stereo_DSD64.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Exploding uri music-library/INTERNAL/Test/2L-056_03_stereo_DSD64.dsf in service mpd Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Adding Item to queue: music-library/INTERNAL/Test/2L-056_04_stereo_DSD128.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Exploding uri music-library/INTERNAL/Test/2L-056_04_stereo_DSD128.dsf in service mpd Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Adding Item to queue: music-library/INTERNAL/Test/2L-056_04_stereo_DSD256.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Exploding uri music-library/INTERNAL/Test/2L-056_04_stereo_DSD256.dsf in service mpd Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Adding Item to queue: music-library/INTERNAL/Test/2L-056_04_stereo_DSD64.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Exploding uri music-library/INTERNAL/Test/2L-056_04_stereo_DSD64.dsf in service mpd Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Adding Item to queue: music-library/INTERNAL/Test/Trace-of-Grace_128.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Exploding uri music-library/INTERNAL/Test/Trace-of-Grace_128.dsf in service mpd Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Adding Item to queue: music-library/INTERNAL/Test/Trace-of-Grace_256.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Exploding uri music-library/INTERNAL/Test/Trace-of-Grace_256.dsf in service mpd Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Adding Item to queue: music-library/INTERNAL/Test/Trace-of-Grace_64.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Exploding uri music-library/INTERNAL/Test/Trace-of-Grace_64.dsf in service mpd Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:11 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces state update: player Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ALBUMART /albumart?cacheid=603&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FTest%2F2L-056_03_stereo_DSD128.dsf&metadata=false Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: URI /mnt/INTERNAL/Test/2L-056_03_stereo_DSD128.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ALBUMART /albumart?cacheid=603&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FTest%2F2L-056_03_stereo_DSD256.dsf&metadata=false Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: URI /mnt/INTERNAL/Test/2L-056_03_stereo_DSD256.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ALBUMART /albumart?cacheid=603&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FTest%2F2L-056_03_stereo_DSD64.dsf&metadata=false Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: URI /mnt/INTERNAL/Test/2L-056_03_stereo_DSD64.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ALBUMART /albumart?cacheid=603&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FTest%2F2L-056_04_stereo_DSD128.dsf&metadata=false Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: URI /mnt/INTERNAL/Test/2L-056_04_stereo_DSD128.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ALBUMART /albumart?cacheid=603&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FTest%2F2L-056_04_stereo_DSD256.dsf&metadata=false Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: URI /mnt/INTERNAL/Test/2L-056_04_stereo_DSD256.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ALBUMART /albumart?cacheid=603&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FTest%2F2L-056_04_stereo_DSD64.dsf&metadata=false Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: URI /mnt/INTERNAL/Test/2L-056_04_stereo_DSD64.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ALBUMART /albumart?cacheid=603&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FTest%2FTrace-of-Grace_128.dsf&metadata=false Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: URI /mnt/INTERNAL/Test/Trace-of-Grace_128.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ALBUMART /albumart?cacheid=603&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FTest%2FTrace-of-Grace_256.dsf&metadata=false Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: URI /mnt/INTERNAL/Test/Trace-of-Grace_256.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ALBUMART /albumart?cacheid=603&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FTest%2FTrace-of-Grace_64.dsf&metadata=false Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: URI /mnt/INTERNAL/Test/Trace-of-Grace_64.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ControllerMpd::getState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::saveQueue Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushQueue Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::updateTrackBlock Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrackBlock Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPlay Apr 27 21:52:11 volumio_raspi3 volumio[1228]: UNSET VOLATILE Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::play index 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::stop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 21:52:11 volumio_raspi3 volumio[1228]: UNSET VOLATILE Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::play index undefined Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::startPlaybackTimer Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Test/2L-056_03_stereo_DSD64.dsf Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand clear Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:11 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces system playlist update Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Ignoring MPD Status Update Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Test/2L-056_03_stereo_DSD64.dsf" Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ControllerMpd::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::servicePushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trace-of-Grace_256.dsf","artist":null,"album":null,"uri":"INTERNAL/Test/Trace-of-Grace_256.dsf","trackType":"dsf"} Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: CURRENT POSITION 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState stateService stop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: No code Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ------------------------------ 81ms Apr 27 21:52:11 volumio_raspi3 volumio[1228]: error: updateQueue error: null Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:11 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces system playlist update Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Ignoring MPD Status Update Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ------------------------------ 44ms Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand play Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ------------------------------ 17ms Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:11 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces state update: player Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ControllerMpd::getState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:11 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces state update: player Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ControllerMpd::getState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ControllerMpd::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::servicePushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":43,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"2L-056_03_stereo_DSD64.dsf","artist":null,"album":null,"uri":"INTERNAL/Test/2L-056_03_stereo_DSD64.dsf","trackType":"dsf"} Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: CURRENT POSITION 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState stateService play Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ------------------------------ 28ms Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ControllerMpd::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::servicePushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40,"duration":43,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"2L-056_03_stereo_DSD64.dsf","artist":null,"album":null,"uri":"INTERNAL/Test/2L-056_03_stereo_DSD64.dsf","trackType":"dsf"} Apr 27 21:52:11 volumio_raspi3 volumio[1228]: verbose: CURRENT POSITION 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState stateService play Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState currentStatus play Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: Received an update from plugin. extracting info from payload Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: ------------------------------ 64ms Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:13 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:14 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:15 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:15 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:15 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:15 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:15 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:15 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:15 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:15 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:16 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:17 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.861&uuid=0f6b61fab940faa767847f04305420b4" http://updates.volumio.org:7070/downloader-v1/track-device Apr 27 21:52:18 volumio_raspi3 volumio[1228]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 27 21:52:18 volumio_raspi3 volumio[1228]: Dload Upload Total Spent Left Speed Apr 27 21:52:18 volumio_raspi3 volumio[1228]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Apr 27 21:52:18 volumio_raspi3 volumio[1228]: retrying in 5 seconds, trial 2 Apr 27 21:52:18 volumio_raspi3 volumio[1228]: Volumio Calling Home Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:18 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:19 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:20 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:21 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:21 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:21 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:21 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:21 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:21 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:21 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:21 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:22 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:23 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:24 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:25 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:26 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:27 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:28 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:28 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:28 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:28 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:28 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:28 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:28 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:28 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:29 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:30 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:31 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:32 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:33 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:34 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:35 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:35 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:35 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:35 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:35 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:35 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:35 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:35 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:36 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:37 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:38 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:39 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:40 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:41 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:42 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:42 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:42 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:42 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:42 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:42 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:42 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:42 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:42 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:43 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:44 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:44 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:44 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:44 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:44 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:44 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:44 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:44 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:45 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:45 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:45 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:45 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:45 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:45 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:45 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:45 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:46 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:47 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:48 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:48 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:48 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:48 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:48 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:48 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:48 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:48 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 2 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: Prefetching next song Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: DOING PREFETCH IN MPD Apr 27 21:52:49 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Test/2L-056_04_stereo_DSD128.dsf" Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:49 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces system playlist update Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: Ignoring MPD Status Update Apr 27 21:52:49 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:49 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces state update: options Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: ------------------------------ 8ms Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: ControllerMpd::getState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:52:49 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:52:49 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: ControllerMpd::pushState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::servicePushState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreStateMachine::startPlaybackTimer Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37918,"duration":43,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"2L-056_03_stereo_DSD64.dsf","artist":null,"album":null,"uri":"INTERNAL/Test/2L-056_03_stereo_DSD64.dsf","trackType":"dsf"} Apr 27 21:52:49 volumio_raspi3 volumio[1228]: verbose: CURRENT POSITION 3 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState stateService play Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState currentStatus play Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: Received an update from plugin. extracting info from payload Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: ------------------------------ 40ms Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:49 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:50 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:51 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:52 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:53 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:54 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces system playlist update Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: Ignoring MPD Status Update Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:54 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces state update: player Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: ControllerMpd::getState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: Apr 27 21:52:54 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces state update: player Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: ------------------------------ 114ms Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: ControllerMpd::getState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: ControllerMpd::pushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::servicePushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"2L-056_04_stereo_DSD128.dsf","artist":null,"album":null,"uri":"INTERNAL/Test/2L-056_04_stereo_DSD128.dsf","trackType":"dsf"} Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: CURRENT POSITION 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState stateService play Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState currentStatus play Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: Received an update from plugin. extracting info from payload Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: ------------------------------ 159ms Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: ControllerMpd::pushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::servicePushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":204,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"2L-056_04_stereo_DSD128.dsf","artist":null,"album":null,"uri":"INTERNAL/Test/2L-056_04_stereo_DSD128.dsf","trackType":"dsf"} Apr 27 21:52:54 volumio_raspi3 volumio[1228]: verbose: CURRENT POSITION 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState stateService play Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState currentStatus play Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: Received an update from plugin. extracting info from payload Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: ------------------------------ 78ms Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:54 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:55 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:55 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:55 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:55 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:55 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:55 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:55 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:55 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:56 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:57 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:58 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:52:59 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:00 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:01 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:02 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:02 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:03 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:04 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:05 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:06 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:07 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPause Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pause Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreStateMachine::stPlaybackTimer Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreStateMachine::servicePause Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::servicePause Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: ControllerMpd::pause Apr 27 21:53:08 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand pause Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: Apr 27 21:53:08 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces state update: player Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: ControllerMpd::getState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:53:08 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:53:08 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: ControllerMpd::pushState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::servicePushState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13702,"duration":204,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"2L-056_04_stereo_DSD128.dsf","artist":null,"album":null,"uri":"INTERNAL/Test/2L-056_04_stereo_DSD128.dsf","trackType":"dsf"} Apr 27 21:53:08 volumio_raspi3 volumio[1228]: verbose: CURRENT POSITION 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState stateService pause Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState currentStatus pause Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreStateMachine::stPlaybackTimer Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: ------------------------------ 16ms Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:08 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:09 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:10 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:11 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::ClearQueue Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::stop Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 21:53:12 volumio_raspi3 volumio[1228]: UNSET VOLATILE Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::updateTrackBlock Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrackBlock Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::stPlaybackTimer Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::serviceStop Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::serviceStop Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: ControllerMpd::stop Apr 27 21:53:12 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::clearPlayQueue Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::saveQueue Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushQueue Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::addQueueItems Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::addQueueItems Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: Adding Item to queue: webkbs/0 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: Exploding uri webkbs/0 in service personal_radio Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: Apr 27 21:53:12 volumio_raspi3 volumio[1228]: ---------------------------- MPD announces state update: player Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: ControllerMpd::getState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:53:12 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:53:12 volumio_raspi3 volumio[1228]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: ControllerMpd::pushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::servicePushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2L-056_04_stereo_DSD128.dsf","artist":null,"album":null,"uri":"INTERNAL/Test/2L-056_04_stereo_DSD128.dsf","trackType":"dsf"} Apr 27 21:53:12 volumio_raspi3 volumio[1228]: verbose: CURRENT POSITION 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState stateService stop Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: No code Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreStateMachine::pushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioPushState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: ------------------------------ 32ms Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CoreCommandRouter::volumioGetState Apr 27 21:53:12 volumio_raspi3 volumio[1228]: info: CorePlayQueue::getTrack 3 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 21:53:12 volumio_raspi3 volumio[1228]: ReferenceError: self is not defined Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at /data/plugins/music_service/personal_radio/index.js:487:12 Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at emitOne (events.js:116:13) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at Request.emit (events.js:211:7) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at emitOne (events.js:116:13) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at ClientRequest.emit (events.js:211:7) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at Socket.socketErrorListener (_http_client.js:387:9) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at emitOne (events.js:116:13) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at Socket.emit (events.js:211:7) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at emitErrorNT (internal/streams/destroy.js:64:8) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 27 21:53:12 volumio_raspi3 volumio[1228]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 21:53:13 volumio_raspi3 sudo[3837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-27 21:52 Apr 27 21:53:13 volumio_raspi3 sudo[3837]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 14:39:52 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="097e28ee6263e31d0bf6b690a02dcab3"