-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sun 2025-06-08 17:22:55 CEST. -- Jun 08 17:21:01 volumio ntpd[807]: receive: Unexpected origin timestamp 0xebf028dc.2339f9ec does not match aorg 0000000000.00000000 from server@193.70.94.182 xmt 0xebf028dd.5be43e11 Jun 08 17:21:01 volumio ntpd[807]: receive: Unexpected origin timestamp 0xebf028dc.2336deb0 does not match aorg 0000000000.00000000 from server@85.115.212.254 xmt 0xebf028dd.5c2fa982 Jun 08 17:21:01 volumio ntpd[807]: receive: Unexpected origin timestamp 0xebf028dc.23278804 does not match aorg 0000000000.00000000 from server@89.161.47.136 xmt 0xebf028dd.5ccddcca Jun 08 17:21:01 volumio ntpd[807]: receive: Unexpected origin timestamp 0xebf028dc.232ae98a does not match aorg 0000000000.00000000 from server@94.154.96.7 xmt 0xebf028dd.5d498304 Jun 08 17:21:01 volumio ntpd[807]: receive: Unexpected origin timestamp 0xebf028dc.231cac02 does not match aorg 0000000000.00000000 from server@91.212.242.20 xmt 0xebf028dd.5c454561 Jun 08 17:21:01 volumio volumio[1135]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 08 17:21:01 volumio volumio[1135]: info: Completed starting MyVolumio Plugin Jun 08 17:21:01 volumio volumio[1135]: [Metrics] CommandRouter: 51s 665.81ms Jun 08 17:21:01 volumio volumio[1135]: info: CoreCommandRouter::volumiosetStartupVolume Jun 08 17:21:01 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:01 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:01 volumio volumio[1135]: info: CoreCommandRouter::Close All Modals sent Jun 08 17:21:01 volumio volumio[1135]: info: CoreCommandRouter::Close All Modals sent Jun 08 17:21:01 volumio volumio[1135]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 08 17:21:01 volumio volumio[1135]: aplay: main:828: audio open error: Device or resource busy Jun 08 17:21:02 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 08 17:21:02 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 08 17:21:02 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::volumioSeek Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::seek Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: info: TRACKBLOCK {"uri":"mnt/USB/UDISK/[8] Pick Yourself Up.mp3","service":"mpd","name":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=591&web=Nat%20King%20%26%20George%20Shearing/Breaking%20Bad/extralarge&path=%2FUSB%2FUDISK&metadata=false","duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: info: ControllerMpd::seek Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:06 volumio volumio[1135]: error: null Jun 08 17:21:06 volumio volumio[1135]: info: Jun 08 17:21:06 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:21:06 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:21:06 volumio volumio[1135]: info: Jun 08 17:21:06 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:21:06 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:21:06 volumio volumio[1135]: info: Jun 08 17:21:06 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:21:06 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:21:06 volumio volumio[1135]: info: sendMpdCommand status took 15 milliseconds Jun 08 17:21:06 volumio volumio[1135]: info: sendMpdCommand status took 18 milliseconds Jun 08 17:21:06 volumio volumio[1135]: info: sendMpdCommand status took 16 milliseconds Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:21:06 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 17:21:06 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 17:21:06 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:21:06 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:21:06 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101000,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:21:06 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:21:06 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:06 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101000,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:21:06 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:21:06 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:06 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":101000,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:21:06 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:21:06 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:06 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:06 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:06 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:06 volumio volumio[1135]: info: ------------------------------ 155ms Jun 08 17:21:06 volumio volumio[1135]: info: ------------------------------ 153ms Jun 08 17:21:06 volumio volumio[1135]: info: ------------------------------ 152ms Jun 08 17:21:08 volumio volumio[1135]: info: BOOT COMPLETED Jun 08 17:21:16 volumio volumio[1135]: info: CoreCommandRouter::volumioPause Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::pause Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::servicePause Jun 08 17:21:16 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:16 volumio volumio[1135]: info: CoreCommandRouter::servicePause Jun 08 17:21:16 volumio volumio[1135]: info: ControllerMpd::pause Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand pause Jun 08 17:21:16 volumio volumio[1135]: info: Jun 08 17:21:16 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:21:16 volumio volumio[1135]: info: sendMpdCommand pause took 4 milliseconds Jun 08 17:21:16 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:21:16 volumio volumio[1135]: info: Jun 08 17:21:16 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:21:16 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:21:16 volumio volumio[1135]: info: Jun 08 17:21:16 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:21:16 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:21:16 volumio volumio[1135]: info: sendMpdCommand status took 9 milliseconds Jun 08 17:21:16 volumio volumio[1135]: info: sendMpdCommand status took 7 milliseconds Jun 08 17:21:16 volumio volumio[1135]: info: sendMpdCommand status took 5 milliseconds Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:21:16 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 17:21:16 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 17:21:16 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:21:16 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:21:16 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:21:16 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:21:16 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:16 volumio volumio[1135]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":112234,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:21:16 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::syncState stateService pause Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus pause Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:16 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:16 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:21:16 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Jun 08 17:21:16 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:21:16 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:21:16 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:16 volumio volumio[1135]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":112234,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:21:16 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::syncState stateService pause Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus pause Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:16 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:16 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Jun 08 17:21:16 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:21:16 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:21:16 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:16 volumio volumio[1135]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":112234,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:21:16 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::syncState stateService pause Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus pause Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:16 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:16 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:16 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Jun 08 17:21:16 volumio volumio[1135]: info: ------------------------------ 90ms Jun 08 17:21:16 volumio volumio[1135]: info: ------------------------------ 88ms Jun 08 17:21:16 volumio volumio[1135]: info: ------------------------------ 86ms Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::volumioPlay Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::play index undefined Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: info: ControllerMpd::resume Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand play Jun 08 17:21:18 volumio volumio[1135]: info: Jun 08 17:21:18 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:21:18 volumio volumio[1135]: info: sendMpdCommand play took 9 milliseconds Jun 08 17:21:18 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:21:18 volumio volumio[1135]: info: Jun 08 17:21:18 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:21:18 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:21:18 volumio volumio[1135]: info: Jun 08 17:21:18 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:21:18 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:21:18 volumio volumio[1135]: info: sendMpdCommand status took 10 milliseconds Jun 08 17:21:18 volumio volumio[1135]: info: sendMpdCommand status took 8 milliseconds Jun 08 17:21:18 volumio volumio[1135]: info: sendMpdCommand status took 5 milliseconds Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:21:18 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 08 17:21:18 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 08 17:21:18 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:21:18 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:21:18 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113232,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:21:18 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus pause Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:18 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113232,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:21:18 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:21:18 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:18 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113232,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:21:18 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:21:18 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:18 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:18 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:18 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:18 volumio volumio[1135]: info: ------------------------------ 126ms Jun 08 17:21:18 volumio volumio[1135]: info: ------------------------------ 124ms Jun 08 17:21:18 volumio volumio[1135]: info: ------------------------------ 122ms Jun 08 17:21:30 volumio volumio[1370]: .........................................................................................................................................................................................................................................++++ Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 08 17:21:34 volumio volumio[1135]: info: Getting Alsa Cards List without I2S DAC Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 08 17:21:34 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 08 17:21:49 volumio volumio[1135]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Jun 08 17:21:49 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:49 volumio volumio[1135]: info: Restoring Previous Volume level: 100 false true Jun 08 17:21:49 volumio volumio[1135]: info: VolumeController::SetAlsaVolume100 Jun 08 17:21:49 volumio volumio[1135]: info: Enable softmixer device for audio device number 1 Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::volumioStop Jun 08 17:21:49 volumio volumio[1135]: info: CoreStateMachine::stop Jun 08 17:21:49 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:21:49 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Jun 08 17:21:49 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:21:49 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:21:49 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:49 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:49 volumio volumio[1135]: info: CoreStateMachine::serviceStop Jun 08 17:21:49 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::serviceStop Jun 08 17:21:49 volumio volumio[1135]: info: ControllerMpd::stop Jun 08 17:21:49 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:21:49 volumio volumio[1135]: info: Enable softmixer device for audio device undefined Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 08 17:21:49 volumio volumio[1135]: info: Updating Volume Controller Parameters: Device: 1 Name: HiFiBerry DAC Mixer: undefined Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 08 17:21:49 volumio volumio[1135]: info: Disabling external Volume Control Jun 08 17:21:49 volumio volumio[1135]: info: Output device has changed, restarting MPD Jun 08 17:21:49 volumio volumio[1135]: info: Output device has changed, restarting Shairport Sync Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:49 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:49 volumio sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 08 17:21:49 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:49 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:49 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 08 17:21:49 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:49 volumio systemd[1]: Stopping Music Player Daemon... Jun 08 17:21:49 volumio volumio[1135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 08 17:21:49 volumio volumio[1135]: info: Volume configurations have been set Jun 08 17:21:49 volumio systemd[1]: mpd.service: Succeeded. Jun 08 17:21:49 volumio systemd[1]: Stopped Music Player Daemon. Jun 08 17:21:50 volumio systemd[1]: Starting Music Player Daemon... Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 08 17:21:50 volumio volumio[1135]: info: Updating Volume Controller Parameters: Device: 1 Name: HiFiBerry DAC Mixer: undefined Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 08 17:21:50 volumio volumio[1135]: info: Disabling external Volume Control Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 17:21:50 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:50 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:50 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:50 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:50 volumio volumio[1135]: error: MPD error: Error: write EPIPE Jun 08 17:21:50 volumio volumio[1135]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jun 08 17:21:50 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:50 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:50 volumio volumio[1135]: info: MPD Permissions set Jun 08 17:21:50 volumio volumio[1135]: error: Upnp client error: Error: This socket has been ended by the other party Jun 08 17:21:50 volumio volumio[1135]: info: Software Volume ALSA configuration written Jun 08 17:21:50 volumio volumio[1135]: info: Preparing to generate the ALSA configuration file Jun 08 17:21:50 volumio sudo[1777]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 08 17:21:50 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:50 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:50 volumio volumio[1135]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 08 17:21:50 volumio volumio[1135]: info: Reading ALSA contributions from plugins. Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 08 17:21:50 volumio volumio[1135]: info: Getting Alsa Cards List without I2S DAC Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 08 17:21:50 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 08 17:21:50 volumio volumio[1135]: info: Starting Shairport Sync Jun 08 17:21:50 volumio volumio[1135]: info: Asound.conf file written Jun 08 17:21:50 volumio sudo[1799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 08 17:21:50 volumio sudo[1799]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 08 17:21:50 volumio sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 08 17:21:50 volumio sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:50 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 08 17:21:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 08 17:21:51 volumio sudo[1802]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 08 17:21:51 volumio sudo[1799]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:51 volumio volumio[1135]: info: Output device has changed, restarting MPD Jun 08 17:21:51 volumio sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 08 17:21:51 volumio volumio[1135]: info: Output device has changed, restarting Shairport Sync Jun 08 17:21:51 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:51 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:51 volumio sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:51 volumio sudo[1811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 08 17:21:51 volumio sudo[1808]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:51 volumio sudo[1811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:51 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 08 17:21:51 volumio systemd[1]: mpd.service: Succeeded. Jun 08 17:21:51 volumio volumio[1135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 08 17:21:51 volumio systemd[1]: Stopped Music Player Daemon. Jun 08 17:21:51 volumio systemd[1]: Starting Music Player Daemon... Jun 08 17:21:51 volumio sudo[1818]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 08 17:21:51 volumio sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:51 volumio sudo[1818]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:52 volumio volumio[1135]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 08 17:21:52 volumio volumio[1135]: info: Output device has changed, restarting MPD Jun 08 17:21:52 volumio volumio[1135]: info: Output device has changed, restarting Shairport Sync Jun 08 17:21:52 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:52 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:52 volumio sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 08 17:21:52 volumio sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:52 volumio sudo[1826]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:52 volumio sudo[1828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 08 17:21:52 volumio sudo[1828]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:53 volumio volumio[1135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 08 17:21:53 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 08 17:21:53 volumio systemd[1]: mpd.service: Succeeded. Jun 08 17:21:53 volumio systemd[1]: Stopped Music Player Daemon. Jun 08 17:21:53 volumio systemd[1]: Starting Music Player Daemon... Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 08 17:21:53 volumio volumio[1135]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 08 17:21:53 volumio volumio[1135]: info: Disabling external Volume Control Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::volumioPlay Jun 08 17:21:53 volumio volumio[1135]: info: CoreStateMachine::play index undefined Jun 08 17:21:53 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:21:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:53 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Jun 08 17:21:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:53 volumio volumio[1135]: verbose: ControllerMpd::clearAddPlayTracks USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:21:53 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:21:53 volumio volumio[1135]: info: MPD Permissions set Jun 08 17:21:53 volumio volumio[1135]: info: MPD Permissions set Jun 08 17:21:53 volumio volumio[1135]: info: Shairport-Sync Started Jun 08 17:21:53 volumio volumio[1135]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'undefined',0 Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio sudo[1834]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio sudo[1834]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio sudo[1834]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:53 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:53 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:21:53 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:53 volumio volumio[1135]: info: Starting Shairport Sync Jun 08 17:21:53 volumio volumio[1135]: info: Starting Shairport Sync Jun 08 17:21:53 volumio sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 08 17:21:53 volumio sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:53 volumio sudo[1857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 08 17:21:53 volumio sudo[1857]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 08 17:21:53 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 08 17:21:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 08 17:21:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 08 17:21:53 volumio sudo[1855]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:53 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 08 17:21:53 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 08 17:21:53 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 08 17:21:53 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 08 17:21:53 volumio volumio[1135]: info: Shairport-Sync Started Jun 08 17:21:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 08 17:21:53 volumio sudo[1857]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:53 volumio volumio[1135]: info: Shairport-Sync Started Jun 08 17:21:54 volumio volumio[1135]: info: VolumeController::SetAlsaVolume100 Jun 08 17:21:54 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:21:54 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:21:54 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:21:54 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:21:54 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 08 17:21:54 volumio sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 08 17:21:54 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:54 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 08 17:21:54 volumio systemd[1]: mpd.service: Succeeded. Jun 08 17:21:54 volumio systemd[1]: Stopped Music Player Daemon. Jun 08 17:21:54 volumio systemd[1]: Starting Music Player Daemon... Jun 08 17:21:55 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:21:55 volumio sudo[1870]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 08 17:21:55 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:21:55 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:57 volumio mpd[1872]: Jun 08 17:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 08 17:21:57 volumio systemd[1]: Started Music Player Daemon. Jun 08 17:21:57 volumio sudo[1811]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:57 volumio volumio[1135]: Upnp client error: Error: This socket has been ended by the other party Jun 08 17:21:57 volumio volumio[1135]: Upnp client error: Error: This socket has been ended by the other party Jun 08 17:21:57 volumio sudo[1828]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:57 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:57 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root Jun 08 17:21:57 volumio volumio[1135]: error: MPD error: The expression evaluated to a falsy value: Jun 08 17:21:57 volumio volumio[1135]: assert.ok(self.idling) Jun 08 17:21:57 volumio volumio[1135]: error: The expression evaluated to a falsy value: Jun 08 17:21:57 volumio volumio[1135]: assert.ok(self.idling) Jun 08 17:21:57 volumio volumio[1135]: error: MPD error: The expression evaluated to a falsy value: Jun 08 17:21:57 volumio volumio[1135]: assert.ok(self.idling) Jun 08 17:21:57 volumio volumio[1135]: error: The expression evaluated to a falsy value: Jun 08 17:21:57 volumio volumio[1135]: assert.ok(self.idling) Jun 08 17:21:57 volumio volumio[1135]: error: MPD error: The expression evaluated to a falsy value: Jun 08 17:21:57 volumio volumio[1135]: assert.ok(self.idling) Jun 08 17:21:57 volumio volumio[1135]: error: The expression evaluated to a falsy value: Jun 08 17:21:57 volumio volumio[1135]: assert.ok(self.idling) Jun 08 17:21:57 volumio volumio[1135]: error: updateQueue error: null Jun 08 17:22:04 volumio volumio[1135]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 08 17:22:04 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 08 17:22:04 volumio volumio[1135]: info: CoreCommandRouter::volumioGetState Jun 08 17:22:04 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:04 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jun 08 17:22:04 volumio volumio[1135]: info: Restoring Previous Volume level: 100 false true Jun 08 17:22:04 volumio volumio[1135]: info: VolumeController::SetAlsaVolume100 Jun 08 17:22:04 volumio sudo[1905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 08 17:22:04 volumio sudo[1905]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:22:04 volumio sudo[1905]: pam_unix(sudo:session): session closed for user root Jun 08 17:22:04 volumio sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 08 17:22:04 volumio volumio[1135]: info: Volume configurations have been set Jun 08 17:22:04 volumio sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:22:04 volumio systemd[1]: Stopping Music Player Daemon... Jun 08 17:22:04 volumio systemd[1]: mpd.service: Succeeded. Jun 08 17:22:04 volumio systemd[1]: Stopped Music Player Daemon. Jun 08 17:22:04 volumio volumio[1135]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 08 17:22:04 volumio volumio[1135]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 08 17:22:04 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 08 17:22:04 volumio volumio[1135]: info: Disabling external Volume Control Jun 08 17:22:04 volumio systemd[1]: Starting Music Player Daemon... Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 17:22:05 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:05 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:05 volumio volumio[1135]: info: MPD Permissions set Jun 08 17:22:05 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:05 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 17:22:05 volumio sudo[1913]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 08 17:22:05 volumio sudo[1913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 17:22:05 volumio sudo[1913]: pam_unix(sudo:session): session closed for user root Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 08 17:22:05 volumio volumio[1135]: info: Getting Alsa Cards List without I2S DAC Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 08 17:22:05 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 08 17:22:05 volumio volumio[1135]: error: Upnp client error: Error: This socket has been ended by the other party Jun 08 17:22:07 volumio mpd[1928]: Jun 08 17:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 08 17:22:07 volumio systemd[1]: Started Music Player Daemon. Jun 08 17:22:07 volumio sudo[1907]: pam_unix(sudo:session): session closed for user root Jun 08 17:22:07 volumio volumio[1135]: error: updateQueue error: null Jun 08 17:22:09 volumio volumio[1135]: info: VolumeController::SetAlsaVolume100 Jun 08 17:22:09 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:09 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:09 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:09 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPlay Jun 08 17:22:10 volumio volumio[1135]: info: CoreStateMachine::play index undefined Jun 08 17:22:10 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:10 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Jun 08 17:22:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::clearAddPlayTracks USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:22:10 volumio volumio[1135]: info: sendMpdCommand stop took 2 milliseconds Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 17:22:10 volumio volumio[1135]: info: Jun 08 17:22:10 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:10 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:10 volumio volumio[1135]: info: sendMpdCommand clear took 3 milliseconds Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand add "USB/UDISK/[8] Pick Yourself Up.mp3" Jun 08 17:22:10 volumio volumio[1135]: error: updateQueue error: null Jun 08 17:22:10 volumio volumio[1135]: info: Jun 08 17:22:10 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:10 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:10 volumio volumio[1135]: info: ------------------------------ 9ms Jun 08 17:22:10 volumio volumio[1135]: info: sendMpdCommand add "USB/UDISK/[8] Pick Yourself Up.mp3" took 5 milliseconds Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand play Jun 08 17:22:10 volumio volumio[1135]: info: ------------------------------ 11ms Jun 08 17:22:10 volumio volumio[1135]: info: sendMpdCommand play took 10 milliseconds Jun 08 17:22:10 volumio volumio[1135]: info: Jun 08 17:22:10 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:10 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:10 volumio volumio[1135]: info: Jun 08 17:22:10 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:10 volumio volumio[1135]: info: sendMpdCommand status took 42 milliseconds Jun 08 17:22:10 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:10 volumio volumio[1135]: info: sendMpdCommand status took 6 milliseconds Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:10 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 08 17:22:10 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:10 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:10 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:10 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:10 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:22:10 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:22:10 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:10 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Jun 08 17:22:10 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:10 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:10 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":183,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Pick Youself Up","artist":"Nat King & George Shearing","album":"Breaking Bad","uri":"USB/UDISK/[8] Pick Yourself Up.mp3","trackType":"mp3"} Jun 08 17:22:10 volumio volumio[1135]: verbose: CURRENT POSITION 7 Jun 08 17:22:10 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:10 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:22:10 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:22:10 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:10 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:10 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:10 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:10 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:10 volumio volumio[1135]: info: ------------------------------ 136ms Jun 08 17:22:10 volumio volumio[1135]: info: ------------------------------ 115ms Jun 08 17:22:20 volumio volumio[1135]: info: VolumeController::SetAlsaVolume70 Jun 08 17:22:20 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:20 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:20 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:20 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:22 volumio volumio[1135]: info: VolumeController::SetAlsaVolume15 Jun 08 17:22:22 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:22 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:22 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:22 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:22 volumio volumio[1135]: info: VolumeController::SetAlsaVolume14 Jun 08 17:22:22 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:22 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:22 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:22 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:23 volumio volumio[1135]: info: VolumeController::SetAlsaVolume22 Jun 08 17:22:23 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:23 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:23 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:23 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:24 volumio volumio[1135]: info: VolumeController::SetAlsaVolume38 Jun 08 17:22:24 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:24 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:24 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:24 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:25 volumio volumio[1135]: info: VolumeController::SetAlsaVolume45 Jun 08 17:22:25 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:25 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:25 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:25 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:26 volumio volumio[1135]: info: VolumeController::SetAlsaVolume43 Jun 08 17:22:26 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:26 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:26 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:26 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:28 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 08 17:22:28 volumio volumio[1135]: info: CURURI: music-library Jun 08 17:22:28 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:30 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 08 17:22:30 volumio volumio[1135]: info: CURURI: music-library/USB Jun 08 17:22:30 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:30 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 08 17:22:30 volumio volumio[1135]: info: CURURI: music-library/USB/UDISK Jun 08 17:22:30 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:31 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:31 volumio volumio[1135]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 08 17:22:31 volumio volumio[1135]: info: CoreStateMachine::ClearQueue Jun 08 17:22:31 volumio volumio[1135]: info: CoreStateMachine::stop Jun 08 17:22:31 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:31 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Jun 08 17:22:31 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:31 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:31 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:31 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:31 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:31 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::serviceStop Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 7 Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::serviceStop Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::stop Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::clearPlayQueue Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::addQueueItems Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::addQueueItems Jun 08 17:22:32 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[2] I Can't Hide It.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[2] I Can't Hide It.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[2] Stay On The Outside.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[2] Stay On The Outside.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[3] On A Clear Day You Can See Forever.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[3] On A Clear Day You Can See Forever.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPlay Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::play index 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::addQueueItems Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::addQueueItems Jun 08 17:22:32 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[7] Goin' Down.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[7] Goin' Down.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[7] Overture.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[7] Overture.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Crystal Blue Persuasion.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Crystal Blue Persuasion.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Up The Junction.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Up The Junction.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] If I Didn't Love You.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] If I Didn't Love You.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] Where Is Santa Claus.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] Where Is Santa Claus.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] Manhattan.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] Manhattan.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::stop Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::play index undefined Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::clearAddPlayTracks USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand stop took 86 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand stop took 15 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:32 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand status took 12 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand clear took 11 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand add "USB/UDISK/[4] Bonfire.mp3" Jun 08 17:22:32 volumio volumio[1135]: error: updateQueue error: null Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:32 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 10ms Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand add "USB/UDISK/[4] Bonfire.mp3" took 7 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand play Jun 08 17:22:32 volumio volumio[1135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 30ms Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 11ms Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand play took 6 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand status took 6 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand status took 3 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bonfire","artist":"Knife Party","album":"Breaking Bad","uri":"USB/UDISK/[4] Bonfire.mp3","trackType":"mp3"} Jun 08 17:22:32 volumio volumio[1135]: verbose: CURRENT POSITION 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 22ms Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bonfire","artist":"Knife Party","album":"Breaking Bad","uri":"USB/UDISK/[4] Bonfire.mp3","trackType":"mp3"} Jun 08 17:22:32 volumio volumio[1135]: verbose: CURRENT POSITION 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:22:32 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 81ms Jun 08 17:22:32 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::ClearQueue Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::stop Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::serviceStop Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::serviceStop Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::stop Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::clearPlayQueue Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::addQueueItems Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::addQueueItems Jun 08 17:22:32 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[2] I Can't Hide It.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[2] I Can't Hide It.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[2] Stay On The Outside.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[2] Stay On The Outside.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[3] On A Clear Day You Can See Forever.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[3] On A Clear Day You Can See Forever.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPlay Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::play index 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::addQueueItems Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::addQueueItems Jun 08 17:22:32 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[7] Goin' Down.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[7] Goin' Down.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[7] Overture.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[7] Overture.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Crystal Blue Persuasion.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Crystal Blue Persuasion.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Up The Junction.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Up The Junction.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] If I Didn't Love You.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] If I Didn't Love You.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] Where Is Santa Claus.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] Where Is Santa Claus.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] Manhattan.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] Manhattan.mp3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::stop Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::play index undefined Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::clearAddPlayTracks USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand stop took 95 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand stop took 21 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:32 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand status took 6 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand clear took 5 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand add "USB/UDISK/[4] Bonfire.mp3" Jun 08 17:22:32 volumio volumio[1135]: error: updateQueue error: null Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:32 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 10ms Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand add "USB/UDISK/[4] Bonfire.mp3" took 6 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand play Jun 08 17:22:32 volumio volumio[1135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 21ms Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 10ms Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand play took 6 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:32 volumio volumio[1135]: info: Jun 08 17:22:32 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand status took 66 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand status took 4 milliseconds Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bonfire","artist":"Knife Party","album":"Breaking Bad","uri":"USB/UDISK/[4] Bonfire.mp3","trackType":"mp3"} Jun 08 17:22:32 volumio volumio[1135]: verbose: CURRENT POSITION 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 83ms Jun 08 17:22:32 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 08 17:22:32 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:32 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":273,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bonfire","artist":"Knife Party","album":"Breaking Bad","uri":"USB/UDISK/[4] Bonfire.mp3","trackType":"mp3"} Jun 08 17:22:32 volumio volumio[1135]: verbose: CURRENT POSITION 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:22:32 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:32 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:32 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:32 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:32 volumio volumio[1135]: info: ------------------------------ 86ms Jun 08 17:22:33 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::ClearQueue Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::stop Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::serviceStop Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::serviceStop Jun 08 17:22:33 volumio volumio[1135]: info: ControllerMpd::stop Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::clearPlayQueue Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::addQueueItems Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::addQueueItems Jun 08 17:22:33 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[2] I Can't Hide It.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[2] I Can't Hide It.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[2] Stay On The Outside.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[2] Stay On The Outside.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[3] On A Clear Day You Can See Forever.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[3] On A Clear Day You Can See Forever.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::volumioPlay Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::play index 3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::addQueueItems Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::addQueueItems Jun 08 17:22:33 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[7] Goin' Down.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[7] Goin' Down.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[7] Overture.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[7] Overture.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Crystal Blue Persuasion.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Crystal Blue Persuasion.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Up The Junction.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Up The Junction.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] If I Didn't Love You.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] If I Didn't Love You.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] Where Is Santa Claus.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] Where Is Santa Claus.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] Manhattan.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] Manhattan.mp3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::stop Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::play index undefined Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::clearAddPlayTracks USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:22:33 volumio volumio[1135]: info: Jun 08 17:22:33 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand stop took 94 milliseconds Jun 08 17:22:33 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand stop took 16 milliseconds Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 17:22:33 volumio volumio[1135]: info: Jun 08 17:22:33 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:33 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand status took 12 milliseconds Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand clear took 10 milliseconds Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand add "USB/UDISK/[4] Bonfire.mp3" Jun 08 17:22:33 volumio volumio[1135]: error: updateQueue error: null Jun 08 17:22:33 volumio volumio[1135]: info: Jun 08 17:22:33 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:33 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:33 volumio volumio[1135]: info: ------------------------------ 13ms Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand add "USB/UDISK/[4] Bonfire.mp3" took 9 milliseconds Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand play Jun 08 17:22:33 volumio volumio[1135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 08 17:22:33 volumio volumio[1135]: info: ------------------------------ 31ms Jun 08 17:22:33 volumio volumio[1135]: info: Jun 08 17:22:33 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:33 volumio volumio[1135]: info: ------------------------------ 11ms Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand play took 7 milliseconds Jun 08 17:22:33 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:33 volumio volumio[1135]: info: Jun 08 17:22:33 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand status took 61 milliseconds Jun 08 17:22:33 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand status took 4 milliseconds Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:33 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:33 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":273,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bonfire","artist":"Knife Party","album":"Breaking Bad","uri":"USB/UDISK/[4] Bonfire.mp3","trackType":"mp3"} Jun 08 17:22:33 volumio volumio[1135]: verbose: CURRENT POSITION 3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Jun 08 17:22:33 volumio volumio[1135]: info: ------------------------------ 79ms Jun 08 17:22:33 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 08 17:22:33 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:33 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:33 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":273,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Bonfire","artist":"Knife Party","album":"Breaking Bad","uri":"USB/UDISK/[4] Bonfire.mp3","trackType":"mp3"} Jun 08 17:22:33 volumio volumio[1135]: verbose: CURRENT POSITION 3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:22:33 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:33 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:33 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:33 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:33 volumio volumio[1135]: info: ------------------------------ 83ms Jun 08 17:22:35 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::ClearQueue Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::stop Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::stPlaybackTimer Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::serviceStop Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrack 3 Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::serviceStop Jun 08 17:22:35 volumio volumio[1135]: info: ControllerMpd::stop Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::clearPlayQueue Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::addQueueItems Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::addQueueItems Jun 08 17:22:35 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[2] I Can't Hide It.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[2] I Can't Hide It.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[2] Stay On The Outside.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[2] Stay On The Outside.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::volumioPlay Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::play index 1 Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::addQueueItems Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::addQueueItems Jun 08 17:22:35 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[3] On A Clear Day You Can See Forever.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[3] On A Clear Day You Can See Forever.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[4] Bonfire.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[7] Goin' Down.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[7] Goin' Down.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[7] Overture.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[7] Overture.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Crystal Blue Persuasion.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Crystal Blue Persuasion.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Pick Yourself Up.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[8] Up The Junction.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[8] Up The Junction.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] If I Didn't Love You.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] If I Didn't Love You.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] Where Is Santa Claus.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] Where Is Santa Claus.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Adding Item to queue: music-library/USB/UDISK/[9] Manhattan.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: Using cached record of: music-library/USB/UDISK/[9] Manhattan.mp3 Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::stop Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::volumioPushQueue Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::saveQueue Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::play index undefined Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::updateTrackBlock Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrackBlock Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::startPlaybackTimer Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::clearAddPlayTracks USB/UDISK/[2] Stay On The Outside.mp3 Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 17:22:35 volumio volumio[1135]: info: Jun 08 17:22:35 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand stop took 75 milliseconds Jun 08 17:22:35 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand stop took 13 milliseconds Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 17:22:35 volumio volumio[1135]: info: Jun 08 17:22:35 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:35 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand status took 6 milliseconds Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand clear took 5 milliseconds Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand add "USB/UDISK/[2] Stay On The Outside.mp3" Jun 08 17:22:35 volumio volumio[1135]: error: updateQueue error: null Jun 08 17:22:35 volumio volumio[1135]: info: Jun 08 17:22:35 volumio volumio[1135]: ---------------------------- MPD announces system playlist update Jun 08 17:22:35 volumio volumio[1135]: info: Ignoring MPD Status Update Jun 08 17:22:35 volumio volumio[1135]: info: ------------------------------ 14ms Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand add "USB/UDISK/[2] Stay On The Outside.mp3" took 11 milliseconds Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand play Jun 08 17:22:35 volumio volumio[1135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 08 17:22:35 volumio volumio[1135]: info: ------------------------------ 27ms Jun 08 17:22:35 volumio volumio[1135]: info: ------------------------------ 10ms Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand play took 5 milliseconds Jun 08 17:22:35 volumio volumio[1135]: info: Jun 08 17:22:35 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:35 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:35 volumio volumio[1135]: info: Jun 08 17:22:35 volumio volumio[1135]: ---------------------------- MPD announces state update: player Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand status took 15 milliseconds Jun 08 17:22:35 volumio volumio[1135]: info: ControllerMpd::getState Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand status Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand status took 19 milliseconds Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::parseState Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:35 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Jun 08 17:22:35 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stay On The Outside","artist":"Whitey","album":"Breaking Bad","uri":"USB/UDISK/[2] Stay On The Outside.mp3","trackType":"mp3"} Jun 08 17:22:35 volumio volumio[1135]: verbose: CURRENT POSITION 1 Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus stop Jun 08 17:22:35 volumio volumio[1135]: info: ------------------------------ 46ms Jun 08 17:22:35 volumio volumio[1135]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 08 17:22:35 volumio volumio[1135]: verbose: ControllerMpd::parseTrackInfo Jun 08 17:22:35 volumio volumio[1135]: info: ControllerMpd::pushState Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::servicePushState Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Jun 08 17:22:35 volumio volumio[1135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":301,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Stay On The Outside","artist":"Whitey","album":"Breaking Bad","uri":"USB/UDISK/[2] Stay On The Outside.mp3","trackType":"mp3"} Jun 08 17:22:35 volumio volumio[1135]: verbose: CURRENT POSITION 1 Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::syncState stateService play Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::syncState currentStatus play Jun 08 17:22:35 volumio volumio[1135]: info: Received an update from plugin. extracting info from payload Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:35 volumio volumio[1135]: info: CoreStateMachine::pushState Jun 08 17:22:35 volumio volumio[1135]: info: CorePlayQueue::getTrack 1 Jun 08 17:22:35 volumio volumio[1135]: info: CoreCommandRouter::volumioPushState Jun 08 17:22:35 volumio volumio[1135]: info: ------------------------------ 93ms Jun 08 17:22:42 volumio volumio[1370]: ...........................................................................................................................................................................................................................................................................++++ Jun 08 17:22:42 volumio volumio[1370]: e is 65537 (0x010001) Jun 08 17:22:42 volumio volumio[1370]: writing RSA key Jun 08 17:22:49 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 08 17:22:49 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:51 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 08 17:22:51 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:52 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 08 17:22:52 volumio volumio[1135]: info: Preload queue cleared Jun 08 17:22:54 volumio volumio[1135]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 08 17:22:54 volumio volumio[1135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 17:22:54 volumio volumio[1135]: TypeError: Cannot read property 'length' of undefined Jun 08 17:22:54 volumio volumio[1135]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 08 17:22:54 volumio volumio[1135]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 08 17:22:54 volumio volumio[1135]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 08 17:22:54 volumio volumio[1135]: at Parser.emit (events.js:315:20) Jun 08 17:22:54 volumio volumio[1135]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 08 17:22:54 volumio volumio[1135]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 08 17:22:54 volumio volumio[1135]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 08 17:22:54 volumio volumio[1135]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 08 17:22:54 volumio volumio[1135]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 08 17:22:54 volumio volumio[1135]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 08 17:22:54 volumio volumio[1135]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 08 17:22:54 volumio volumio[1135]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 08 17:22:54 volumio volumio[1135]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 08 17:22:54 volumio volumio[1135]: at IncomingMessage.emit (events.js:327:22) Jun 08 17:22:54 volumio volumio[1135]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 08 17:22:54 volumio volumio[1135]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 08 17:22:54 volumio volumio[1135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 17:22:55 volumio sudo[2185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 17:21 Jun 08 17:22:55 volumio sudo[2185]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"