Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Preload queue cleared Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::ClearQueue Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::stop Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CorePlayQueue::clearPlayQueue Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CorePlayQueue::saveQueue Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushQueue Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::addQueueItems Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CorePlayQueue::addQueueItems Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Preload queue cleared Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1847171 Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushQueue Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CorePlayQueue::saveQueue Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::updateTrackBlock Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrackBlock Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPlay Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::play index 0 Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::stop Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::play index undefined Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreStateMachine::startPlaybackTimer Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:00 volumiopi4 volumio[1217]: info: [1750510920394] ControllerWebradio::clearAddPlayTrack Jun 21 15:02:00 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 15:02:00 volumiopi4 volumio[1217]: info: sendMpdCommand stop took 3 milliseconds Jun 21 15:02:00 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand clear Jun 21 15:02:00 volumiopi4 volumio[1217]: info: sendMpdCommand clear took 0 milliseconds Jun 21 15:02:00 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1847171" Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Received Get System Info Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Discovery: Getting this device information Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:00 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Jun 21 15:02:00 volumiopi4 volumio[1217]: ---------------------------- MPD announces system playlist update Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Ignoring MPD Status Update Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Jun 21 15:02:00 volumiopi4 volumio[1217]: ---------------------------- MPD announces system playlist update Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Ignoring MPD Status Update Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Jun 21 15:02:00 volumiopi4 volumio[1217]: ---------------------------- MPD announces system playlist update Jun 21 15:02:00 volumiopi4 volumio[1217]: info: Ignoring MPD Status Update Jun 21 15:02:01 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 15:02:01 volumiopi4 volumio[1217]: info: Received Get System Info Jun 21 15:02:01 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 15:02:01 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 15:02:01 volumiopi4 volumio[1217]: info: Discovery: Getting this device information Jun 21 15:02:01 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 21 15:02:01 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:01 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 15:02:01 volumiopi4 volumio[1217]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1847171" took 1129 milliseconds Jun 21 15:02:01 volumiopi4 volumio[1217]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 15:02:01 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand play Jun 21 15:02:01 volumiopi4 volumio[1217]: info: Jun 21 15:02:01 volumiopi4 volumio[1217]: ---------------------------- MPD announces system playlist update Jun 21 15:02:01 volumiopi4 volumio[1217]: info: Ignoring MPD Status Update Jun 21 15:02:01 volumiopi4 volumio[1217]: info: ------------------------------ 1127ms Jun 21 15:02:01 volumiopi4 volumio[1217]: info: ------------------------------ 1126ms Jun 21 15:02:01 volumiopi4 volumio[1217]: info: ------------------------------ 1125ms Jun 21 15:02:01 volumiopi4 volumio[1217]: info: Jun 21 15:02:01 volumiopi4 volumio[1217]: ---------------------------- MPD announces system playlist update Jun 21 15:02:01 volumiopi4 volumio[1217]: info: Ignoring MPD Status Update Jun 21 15:02:01 volumiopi4 volumio[1217]: info: Jun 21 15:02:01 volumiopi4 volumio[1217]: ---------------------------- MPD announces system playlist update Jun 21 15:02:01 volumiopi4 volumio[1217]: info: Ignoring MPD Status Update Jun 21 15:02:01 volumiopi4 volumio[1217]: info: sendMpdCommand play took 7 milliseconds Jun 21 15:02:01 volumiopi4 volumio[1217]: info: ------------------------------ 7ms Jun 21 15:02:01 volumiopi4 volumio[1217]: info: ------------------------------ 3ms Jun 21 15:02:01 volumiopi4 volumio[1217]: info: ------------------------------ 3ms Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Jun 21 15:02:02 volumiopi4 volumio[1217]: ---------------------------- MPD announces state update: player Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::getState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Jun 21 15:02:02 volumiopi4 volumio[1217]: ---------------------------- MPD announces state update: player Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::getState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Jun 21 15:02:02 volumiopi4 volumio[1217]: ---------------------------- MPD announces state update: player Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::getState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand status took 10 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Jun 21 15:02:02 volumiopi4 volumio[1217]: ---------------------------- MPD announces state update: player Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::getState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Jun 21 15:02:02 volumiopi4 volumio[1217]: ---------------------------- MPD announces state update: player Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::getState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand status took 13 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand status took 13 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand status took 2 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::servicePushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"deephouse_64","artist":"1.FM - Deep House (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/deephouse_64","trackType":""} Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: CURRENT POSITION 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState stateService play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ------------------------------ 22ms Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Jun 21 15:02:02 volumiopi4 volumio[1217]: ---------------------------- MPD announces state update: player Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::getState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand status took 9 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand status took 2 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseState Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::servicePushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"deephouse_64","artist":"1.FM - Deep House (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/deephouse_64","trackType":""} Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: CURRENT POSITION 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState stateService play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Received an update from plugin. extracting info from payload Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::servicePushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"deephouse_64","artist":"1.FM - Deep House (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/deephouse_64","trackType":""} Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: CURRENT POSITION 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState stateService play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Received an update from plugin. extracting info from payload Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::servicePushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"deephouse_64","artist":"1.FM - Deep House (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/deephouse_64","trackType":""} Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: CURRENT POSITION 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState stateService play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Received an update from plugin. extracting info from payload Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ------------------------------ 60ms Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ------------------------------ 60ms Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ------------------------------ 50ms Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand playlistinfo took 39 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: info: sendMpdCommand playlistinfo took 38 milliseconds Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::servicePushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"deephouse_64","artist":"1.FM - Deep House (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/deephouse_64","trackType":""} Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: CURRENT POSITION 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState stateService play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Received an update from plugin. extracting info from payload Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ControllerMpd::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::servicePushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"deephouse_64","artist":"1.FM - Deep House (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/deephouse_64","trackType":""} Jun 21 15:02:02 volumiopi4 volumio[1217]: verbose: CURRENT POSITION 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState stateService play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jun 21 15:02:02 volumiopi4 volumio[1217]: info: Received an update from plugin. extracting info from payload Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ------------------------------ 76ms Jun 21 15:02:02 volumiopi4 volumio[1217]: info: ------------------------------ 68ms Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Jun 21 15:02:03 volumiopi4 volumio[1217]: ---------------------------- MPD announces system playlist update Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Ignoring MPD Status Update Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Jun 21 15:02:03 volumiopi4 volumio[1217]: ---------------------------- MPD announces state update: player Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ControllerMpd::getState Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Jun 21 15:02:03 volumiopi4 volumio[1217]: ---------------------------- MPD announces system playlist update Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Ignoring MPD Status Update Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Jun 21 15:02:03 volumiopi4 volumio[1217]: ---------------------------- MPD announces state update: player Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ControllerMpd::getState Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Jun 21 15:02:03 volumiopi4 volumio[1217]: ---------------------------- MPD announces system playlist update Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Ignoring MPD Status Update Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Jun 21 15:02:03 volumiopi4 volumio[1217]: ---------------------------- MPD announces state update: player Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ControllerMpd::getState Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ------------------------------ 7ms Jun 21 15:02:03 volumiopi4 volumio[1217]: info: sendMpdCommand status took 4 milliseconds Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ------------------------------ 5ms Jun 21 15:02:03 volumiopi4 volumio[1217]: info: sendMpdCommand status took 3 milliseconds Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseState Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseState Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ------------------------------ 5ms Jun 21 15:02:03 volumiopi4 volumio[1217]: info: sendMpdCommand status took 3 milliseconds Jun 21 15:02:03 volumiopi4 volumio[1217]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 21 15:02:03 volumiopi4 volumio[1217]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseState Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ControllerMpd::pushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::servicePushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Tidal Waves, Luca Aprile - You Are A Traveler","artist":"1.FM - Deep House Radio","album":null,"uri":"http://185.33.21.112:80/deephouse_64","trackType":""} Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: CURRENT POSITION 0 Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState stateService play Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Received an update from plugin. extracting info from payload Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ControllerMpd::pushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::servicePushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Tidal Waves, Luca Aprile - You Are A Traveler","artist":"1.FM - Deep House Radio","album":null,"uri":"http://185.33.21.112:80/deephouse_64","trackType":""} Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: CURRENT POSITION 0 Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState stateService play Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Received an update from plugin. extracting info from payload Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ------------------------------ 34ms Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ------------------------------ 32ms Jun 21 15:02:03 volumiopi4 volumio[1217]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: ControllerMpd::parseTrackInfo Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ControllerMpd::pushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::servicePushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CorePlayQueue::getTrack 0 Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Tidal Waves, Luca Aprile - You Are A Traveler","artist":"1.FM - Deep House Radio","album":null,"uri":"http://185.33.21.112:80/deephouse_64","trackType":""} Jun 21 15:02:03 volumiopi4 volumio[1217]: verbose: CURRENT POSITION 0 Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState stateService play Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::syncState currentStatus play Jun 21 15:02:03 volumiopi4 volumio[1217]: info: Received an update from plugin. extracting info from payload Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreStateMachine::pushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioPushState Jun 21 15:02:03 volumiopi4 volumio[1217]: info: ------------------------------ 48ms Jun 21 15:02:04 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 21 15:02:07 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 15:02:07 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 21 15:02:11 volumiopi4 volumio[1217]: info: Enabling plugin touch_display Jun 21 15:02:11 volumiopi4 volumio[1217]: info: Loading plugin "touch_display"... Jun 21 15:02:11 volumiopi4 volumio[1217]: info: Applying required configuration parameters for plugin touch_display Jun 21 15:02:11 volumiopi4 volumio[1217]: info: PLUGIN START: touch_display Jun 21 15:02:11 volumiopi4 volumio[1217]: info: Loading i18n strings for locale en Jun 21 15:02:11 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 15:02:11 volumiopi4 sudo[2265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 21 15:02:11 volumiopi4 sudo[2265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:11 volumiopi4 volumio[1217]: info: touch_display: Backlight interface detected. Jun 21 15:02:11 volumiopi4 sudo[2267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 21 15:02:11 volumiopi4 volumio[1217]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 21 15:02:11 volumiopi4 sudo[2267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:11 volumiopi4 sudo[2269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 21 15:02:11 volumiopi4 sudo[2269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:11 volumiopi4 systemd[1]: Stopping getty@tty1.service - Getty on tty1... Jun 21 15:02:11 volumiopi4 systemd[1]: getty@tty1.service: Deactivated successfully. Jun 21 15:02:11 volumiopi4 systemd[1]: Stopped getty@tty1.service - Getty on tty1. Jun 21 15:02:11 volumiopi4 volumio[1217]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 21 15:02:11 volumiopi4 systemd[1]: Reloading. Jun 21 15:02:11 volumiopi4 sudo[2276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/backlight_gpio/brightness Jun 21 15:02:11 volumiopi4 sudo[2276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:11 volumiopi4 sudo[2278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 21 15:02:11 volumiopi4 sudo[2278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:11 volumiopi4 sudo[2276]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:11 volumiopi4 sudo[2278]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:11 volumiopi4 volumio[1217]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 21 15:02:11 volumiopi4 volumio[1217]: info: touch_display: File permissions for backlight brightness control set. Jun 21 15:02:11 volumiopi4 volumio[1217]: error: touch_display: Error setting display brightness: Error: EINVAL: invalid argument, write Jun 21 15:02:12 volumiopi4 sudo[2267]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:12 volumiopi4 volumio[1217]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 21 15:02:12 volumiopi4 sudo[2265]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:12 volumiopi4 volumio[1217]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 21 15:02:12 volumiopi4 systemd[1]: Reloading. Jun 21 15:02:12 volumiopi4 volumio[1217]: info: Disabling plugin now_playing Jun 21 15:02:12 volumiopi4 volumio[1217]: info: Done. Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 21 15:02:13 volumiopi4 volumio[1217]: info: Received Get System Version Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 15:02:13 volumiopi4 volumio[1217]: info: Received Get System Info Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 15:02:13 volumiopi4 volumio[1217]: info: Discovery: Getting this device information Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 15:02:13 volumiopi4 sudo[2269]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:13 volumiopi4 volumio[1217]: info: touch_display: systemctl daemon-reload succeeded. Jun 21 15:02:13 volumiopi4 sudo[2321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 21 15:02:13 volumiopi4 sudo[2321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:13 volumiopi4 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Jun 21 15:02:13 volumiopi4 sudo[2321]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:13 volumiopi4 volumio[1217]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 21 15:02:13 volumiopi4 volumio[1217]: info: touch_display: Volumio Kiosk started. Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 21 15:02:13 volumiopi4 volumio[1217]: info: Done. Jun 21 15:02:13 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 21 15:02:13 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:13 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:13 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:13 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:13 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:14 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:14 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:14 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:14 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:14 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:14 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:14 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:14 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:14 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:15 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:16 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:16 volumiopi4 volumio[1217]: info: Starting Uninstall of plugin user_interface - now_playing Jun 21 15:02:16 volumiopi4 volumio[1217]: info: Uninstalling plugin now_playing Jun 21 15:02:16 volumiopi4 volumio[1217]: info: Disabling plugin now_playing Jun 21 15:02:16 volumiopi4 volumio[1217]: info: Checking if uninstall.sh is present Jun 21 15:02:16 volumiopi4 volumio[1217]: info: Executing uninstall.sh Jun 21 15:02:16 volumiopi4 sudo[2382]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/uninstall.sh Jun 21 15:02:16 volumiopi4 sudo[2382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:16 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:16 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:16 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:16 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:16 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:16 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:16 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:16 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:17 volumiopi4 volumio[1217]: info: touch_display: X display number found: Jun 21 15:02:21 volumiopi4 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 21 15:02:23 volumiopi4 sudo[2382]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:23 volumiopi4 volumio[1217]: info: Uninstall script completed Jun 21 15:02:23 volumiopi4 volumio[1217]: info: Removing plugin now_playing from configuration Jun 21 15:02:39 volumiopi4 volumio[1217]: info: Successfully removed now_playing configuration files Jun 21 15:02:39 volumiopi4 volumio[1217]: info: Plugin folders cleanup Jun 21 15:02:39 volumiopi4 volumio[1217]: info: Scanning into folder /volumio/app/plugins/ Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Scanning category audio_interface Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Scanning category miscellanea Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Scanning category music_service Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Scanning category plugins.json Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Scanning category system_controller Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Scanning category user_interface Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Scanning into folder /data/plugins/ Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Scanning category system_controller Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Scanning category user_interface Jun 21 15:02:40 volumiopi4 volumio[1217]: info: Cleaning folder for now_playing Jun 21 15:02:41 volumiopi4 startx[2479]: X.Org X Server 1.21.1.7 Jun 21 15:02:41 volumiopi4 startx[2479]: X Protocol Version 11, Revision 0 Jun 21 15:02:41 volumiopi4 startx[2479]: Current Operating System: Linux volumiopi4 6.12.27-v7l+ #1876 SMP Tue May 6 15:10:05 BST 2025 armv7l Jun 21 15:02:41 volumiopi4 startx[2479]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 numa=fake=1 system_heap.max_order=0 smsc95xx.macaddr=E4:5F:01:CF:18:B1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=0ca921d0-ed29-48f1-bc74-550f2ad8e370 imgfile=/volumio_current.sqsh bootpart=UUID=E54B-2C41 datapart=UUID=9cfbeebe-d04d-4377-b93c-47b70d48adf5 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jun 21 15:02:41 volumiopi4 startx[2479]: xorg-server 2:21.1.7-3+rpt3+deb12u9 (https://www.debian.org/support) Jun 21 15:02:41 volumiopi4 startx[2479]: Current version of pixman: 0.44.0 Jun 21 15:02:41 volumiopi4 startx[2479]: Before reporting problems, check http://wiki.x.org Jun 21 15:02:41 volumiopi4 startx[2479]: to make sure that you have the latest version. Jun 21 15:02:41 volumiopi4 startx[2479]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 21 15:02:41 volumiopi4 startx[2479]: (++) from command line, (!!) notice, (II) informational, Jun 21 15:02:41 volumiopi4 startx[2479]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 21 15:02:41 volumiopi4 startx[2479]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Jun 21 15:02:41 2025 Jun 21 15:02:41 volumiopi4 startx[2479]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 21 15:02:41 volumiopi4 startx[2479]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 21 15:02:41 volumiopi4 volumio[1217]: Upnp client error: Error: This socket has been ended by the other party Jun 21 15:02:41 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 15:02:41 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 15:02:41 volumiopi4 volumio[1217]: info: Discovery: Getting this device information Jun 21 15:02:41 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 21 15:02:41 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 15:02:41 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 15:02:41 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 15:02:41 volumiopi4 volumio[1217]: info: Discovery: Getting this device information Jun 21 15:02:41 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 21 15:02:41 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 15:02:41 volumiopi4 volumio[1217]: info: touch_display: X display number found: 0 Jun 21 15:02:41 volumiopi4 volumio[1217]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 21 15:02:41 volumiopi4 volumio[1217]: info: touch_display: X display number found: 0 Jun 21 15:02:43 volumiopi4 volumio[1217]: info: CoreCommandRouter::volumioGetState Jun 21 15:02:43 volumiopi4 volumio[1217]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 21 15:02:46 volumiopi4 sudo[2576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 21 15:02:46 volumiopi4 sudo[2576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:46 volumiopi4 sudo[2578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Jun 21 15:02:46 volumiopi4 sudo[2578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:46 volumiopi4 sudo[2580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Jun 21 15:02:46 volumiopi4 sudo[2580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:46 volumiopi4 sudo[2583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Jun 21 15:02:46 volumiopi4 sudo[2583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:02:47 volumiopi4 sudo[2576]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:47 volumiopi4 volumio[1217]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jun 21 15:02:47 volumiopi4 systemd[1]: Reloading. Jun 21 15:02:47 volumiopi4 sudo[2580]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:47 volumiopi4 volumio[1217]: info: touch_display: systemctl enable getty@tty1.service succeeded. Jun 21 15:02:47 volumiopi4 systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 21 15:02:48 volumiopi4 sudo[2578]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:48 volumiopi4 startx[2478]: xinit: connection to X server lost Jun 21 15:02:48 volumiopi4 startx[2478]: Jun 21 15:02:48 volumiopi4 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Jun 21 15:02:48 volumiopi4 volumio[1217]: info: touch_display: systemctl start getty@tty1.service succeeded. Jun 21 15:02:48 volumiopi4 startx[2478]: waiting for X server to shut down Jun 21 15:02:48 volumiopi4 startx[2479]: (II) Server terminated successfully (0). Closing log file. Jun 21 15:02:50 volumiopi4 startx[2478]: . Jun 21 15:02:50 volumiopi4 startx[2478]: xinit: unexpected signal 15 Jun 21 15:02:50 volumiopi4 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Jun 21 15:02:50 volumiopi4 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Jun 21 15:02:50 volumiopi4 systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Jun 21 15:02:50 volumiopi4 systemd[1]: volumio-kiosk.service: Consumed 1.745s CPU time. Jun 21 15:02:50 volumiopi4 sudo[2583]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:50 volumiopi4 volumio[1217]: info: touch_display: systemctl stop volumio-kiosk.service succeeded. Jun 21 15:02:50 volumiopi4 volumio[1217]: info: Disabling plugin touch_display Jun 21 15:02:50 volumiopi4 volumio[1217]: info: Done. Jun 21 15:02:57 volumiopi4 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 21 15:02:57 volumiopi4 dbus-daemon[664]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.70' (uid=0 pid=2659 comm="timedatectl show --property=NTPSynchronized --valu") Jun 21 15:02:57 volumiopi4 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 21 15:02:57 volumiopi4 dbus-daemon[664]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 21 15:02:57 volumiopi4 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 21 15:02:57 volumiopi4 setdatetime-helper.sh[2658]: Time is not synchronized. Attempting to sync... Jun 21 15:02:57 volumiopi4 volumio[1217]: info: Starting Uninstall of plugin user_interface - now_playing Jun 21 15:02:59 volumiopi4 sudo[2685]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 21 Jun 2025 13:02:59 GMT#015' Jun 21 15:02:59 volumiopi4 sudo[2685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 21 15:02:59 volumiopi4 setdatetime-helper.sh[2686]: Sat Jun 21 15:02:59 SAST 2025 Jun 21 15:02:59 volumiopi4 setdatetime-helper.sh[2658]: Time synchronized successfully. Jun 21 15:02:59 volumiopi4 sudo[2685]: pam_unix(sudo:session): session closed for user root Jun 21 15:02:59 volumiopi4 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 21 15:02:59 volumiopi4 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 21 15:02:59 volumiopi4 systemd-journald[342]: Time jumped backwards, rotating. Jun 21 15:03:05 volumiopi4 volumio[1217]: info: Starting Uninstall of plugin user_interface - now_playing Jun 21 15:03:09 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 21 15:03:19 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 15:03:19 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 21 15:03:22 volumiopi4 volumio[1217]: info: Enabling plugin now_playing Jun 21 15:03:22 volumiopi4 volumio[1217]: info: Loading plugin "now_playing"... Jun 21 15:03:22 volumiopi4 volumio[1217]: error: !!!! WARNING !!!! Jun 21 15:03:22 volumiopi4 volumio[1217]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module '/data/plugins//user_interface/now_playing/./dist/index.js' Jun 21 15:03:22 volumiopi4 volumio[1217]: Require stack: Jun 21 15:03:22 volumiopi4 volumio[1217]: - /volumio/app/pluginmanager.js Jun 21 15:03:22 volumiopi4 volumio[1217]: - /volumio/app/index.js Jun 21 15:03:22 volumiopi4 volumio[1217]: - /volumio/index.js Jun 21 15:03:22 volumiopi4 volumio[1217]: error: Stack trace: Error: Cannot find module '/data/plugins//user_interface/now_playing/./dist/index.js' Jun 21 15:03:22 volumiopi4 volumio[1217]: Require stack: Jun 21 15:03:22 volumiopi4 volumio[1217]: - /volumio/app/pluginmanager.js Jun 21 15:03:22 volumiopi4 volumio[1217]: - /volumio/app/index.js Jun 21 15:03:22 volumiopi4 volumio[1217]: - /volumio/index.js Jun 21 15:03:22 volumiopi4 volumio[1217]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Jun 21 15:03:22 volumiopi4 volumio[1217]: at Module._load (node:internal/modules/cjs/loader:901:27) Jun 21 15:03:22 volumiopi4 volumio[1217]: at Module.require (node:internal/modules/cjs/loader:1115:19) Jun 21 15:03:22 volumiopi4 volumio[1217]: at require (node:internal/modules/helpers:130:18) Jun 21 15:03:22 volumiopi4 volumio[1217]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Jun 21 15:03:22 volumiopi4 volumio[1217]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 21 15:03:22 volumiopi4 volumio[1217]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 21 15:03:22 volumiopi4 volumio[1217]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jun 21 15:03:22 volumiopi4 volumio[1217]: error: !!!! WARNING !!!! Jun 21 15:03:22 volumiopi4 volumio[1217]: info: Done. Jun 21 15:03:24 volumiopi4 ntpd[979]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 21 15:03:24 volumiopi4 ntpd[979]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 21 15:03:24 volumiopi4 ntpd[979]: DNS: Pool taking: 196.10.52.58 Jun 21 15:03:24 volumiopi4 ntpd[979]: DNS: Pool skipping: 102.216.37.99 Jun 21 15:03:24 volumiopi4 ntpd[979]: DNS: Pool skipping: 102.64.112.165 Jun 21 15:03:24 volumiopi4 ntpd[979]: DNS: Pool taking: 102.222.102.153 Jun 21 15:03:24 volumiopi4 ntpd[979]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 21 15:03:26 volumiopi4 volumio[1217]: info: Enabling plugin touch_display Jun 21 15:03:26 volumiopi4 volumio[1217]: info: Loading plugin "touch_display"... Jun 21 15:03:26 volumiopi4 volumio[1217]: info: Applying required configuration parameters for plugin touch_display Jun 21 15:03:26 volumiopi4 volumio[1217]: info: PLUGIN START: touch_display Jun 21 15:03:26 volumiopi4 volumio[1217]: info: Loading i18n strings for locale en Jun 21 15:03:26 volumiopi4 volumio[1217]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 15:03:26 volumiopi4 volumio[1217]: info: touch_display: Backlight interface detected. Jun 21 15:03:26 volumiopi4 sudo[2775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 21 15:03:26 volumiopi4 sudo[2775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:03:26 volumiopi4 sudo[2777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 21 15:03:26 volumiopi4 sudo[2777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:03:26 volumiopi4 sudo[2779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 21 15:03:26 volumiopi4 sudo[2779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:03:26 volumiopi4 volumio[1217]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 21 15:03:26 volumiopi4 sudo[2783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/backlight_gpio/brightness Jun 21 15:03:26 volumiopi4 sudo[2783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:03:26 volumiopi4 sudo[2783]: pam_unix(sudo:session): session closed for user root Jun 21 15:03:26 volumiopi4 sudo[2786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 21 15:03:26 volumiopi4 sudo[2786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:03:26 volumiopi4 sudo[2786]: pam_unix(sudo:session): session closed for user root Jun 21 15:03:26 volumiopi4 volumio[1217]: info: touch_display: File permissions for backlight brightness control set. Jun 21 15:03:26 volumiopi4 volumio[1217]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 21 15:03:26 volumiopi4 volumio[1217]: error: touch_display: Error setting display brightness: Error: EINVAL: invalid argument, write Jun 21 15:03:26 volumiopi4 sudo[2790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 21 15:03:26 volumiopi4 sudo[2790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 21 15:03:26 volumiopi4 volumio[1217]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 15:03:26 volumiopi4 systemd[1]: Reloading. Jun 21 15:03:26 volumiopi4 sudo[2790]: pam_unix(sudo:session): session closed for user root Jun 21 15:03:26 volumiopi4 volumio[1217]: TypeError: Cannot read properties of null (reading 'printToastMessage') Jun 21 15:03:26 volumiopi4 volumio[1217]: at /volumio/app/index.js:1214:32 Jun 21 15:03:26 volumiopi4 volumio[1217]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Jun 21 15:03:26 volumiopi4 volumio[1217]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Jun 21 15:03:26 volumiopi4 volumio[1217]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1212:13) Jun 21 15:03:26 volumiopi4 volumio[1217]: at /data/plugins/user_interface/touch_display/index.js:875:26 Jun 21 15:03:26 volumiopi4 volumio[1217]: at /data/plugins/user_interface/touch_display/node_modules/graceful-fs/graceful-fs.js:143:16 Jun 21 15:03:26 volumiopi4 volumio[1217]: at node:fs:2251:11 Jun 21 15:03:26 volumiopi4 volumio[1217]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 Jun 21 15:03:26 volumiopi4 volumio[1217]: at FSReqCallback.oncomplete (node:fs:190:23) Jun 21 15:03:26 volumiopi4 volumio[1217]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 15:03:27 volumiopi4 sudo[2826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-21 15:02' Jun 21 15:03:27 volumiopi4 sudo[2826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:24:14 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b365b054264e3ebc2709665da2b5d1ee"