-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-11-26 10:54:50 CET. -- Nov 26 10:53:00 test volumio-remote-updater[707]: No test mode Nov 26 10:53:00 test volumio-remote-updater[707]: No alpha test mode Nov 26 10:53:00 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 26 10:53:00 test volumio[6834]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 26 10:53:00 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 26 10:53:01 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:53:01 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:53:01 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 26 10:53:01 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 26 10:53:01 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 26 10:53:01 test volumio[6834]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 10:53:01 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 10:53:02 test volumio[6834]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 26 10:53:02 test volumio[6834]: info: Completed starting MyVolumio Plugin Nov 26 10:53:02 test volumio[6834]: [Metrics] CommandRouter: 39s 443.33ms Nov 26 10:53:02 test volumio[6834]: info: CoreCommandRouter::volumiosetStartupVolume Nov 26 10:53:02 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:53:02 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:53:02 test volumio[6834]: info: CoreCommandRouter::Close All Modals sent Nov 26 10:53:02 test volumio[6834]: info: CoreCommandRouter::Close All Modals sent Nov 26 10:53:03 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 26 10:53:03 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 10:53:03 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 26 10:53:03 test volumio[6834]: info: CoreCommandRouter::volumioSeek Nov 26 10:53:03 test volumio[6834]: info: CoreStateMachine::seek Nov 26 10:53:03 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:03 test volumio[6834]: info: TRACKBLOCK {"service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"nineties","albumart":"/albumart?sourceicon=music_service/80s80s/images/90s90s-rap-cover.png","uri":"http://streams.90s90s.de/hiphop/mp3-192/volumio","name":"90s90s HipHop & Rap","duration":1000} Nov 26 10:53:03 test volumio[6834]: info: CoreStateMachine::startPlaybackTimer Nov 26 10:53:03 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:03 test volumio[6834]: info: [1732614783852] [80s80s] seek to 482000 Nov 26 10:53:03 test volumio[6834]: info: CoreStateMachine::pushState Nov 26 10:53:03 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:03 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:53:03 test volumio[6834]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:07 test ntpd[844]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 26 10:53:08 test volumio[6834]: info: CoreCommandRouter::volumioGetState Nov 26 10:53:08 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: info: CoreCommandRouter::volumioPlay Nov 26 10:53:09 test volumio[6834]: info: CoreStateMachine::play index undefined Nov 26 10:53:09 test volumio[6834]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: info: CoreStateMachine::startPlaybackTimer Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: info: [1732614789226] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/hiphop/mp3-192/volumio Nov 26 10:53:09 test volumio[6834]: info: [1732614789227] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=265&count=2 Nov 26 10:53:09 test volumio[6834]: info: BOOT COMPLETED Nov 26 10:53:09 test volumio[6834]: info: [1732614789412] [80s80s] received new event containing 2 songs. Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand stop Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand stop took 2 milliseconds Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand clear Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand clear took 6 milliseconds Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 26 10:53:09 test volumio[6834]: info: Nov 26 10:53:09 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:09 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:09 test volumio[6834]: info: Nov 26 10:53:09 test volumio[6834]: ---------------------------- MPD announces state update: options Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand consume 1 took 2 milliseconds Nov 26 10:53:09 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:09 test volumio[6834]: info: [1732614789425] [80s80s] adding url: http://streams.90s90s.de/hiphop/mp3-192/volumio Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/hiphop/mp3-192/volumio" Nov 26 10:53:09 test volumio[6834]: info: Nov 26 10:53:09 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:09 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:09 test volumio[6834]: info: Nov 26 10:53:09 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:09 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:09 test volumio[6834]: error: updateQueue error: null Nov 26 10:53:09 test volumio[6834]: info: Nov 26 10:53:09 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:09 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:09 test volumio[6834]: info: ------------------------------ 9ms Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand status took 6 milliseconds Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand add "http://streams.90s90s.de/hiphop/mp3-192/volumio" took 6 milliseconds Nov 26 10:53:09 test volumio[6834]: info: ------------------------------ 6ms Nov 26 10:53:09 test volumio[6834]: info: ------------------------------ 5ms Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand play Nov 26 10:53:09 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:09 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:09 test volumio[6834]: info: CoreStateMachine::pushState Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:53:09 test volumio[6834]: info: CoreCommandRouter::volumioPushState Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:09 test volumio[6834]: info: ------------------------------ 33ms Nov 26 10:53:09 test volumio[6834]: info: Nov 26 10:53:09 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:09 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:09 test volumio[6834]: info: Nov 26 10:53:09 test volumio[6834]: ---------------------------- MPD announces state update: options Nov 26 10:53:09 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:09 test volumio[6834]: info: Nov 26 10:53:09 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:09 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:09 test volumio[6834]: info: Nov 26 10:53:09 test volumio[6834]: ---------------------------- MPD announces state update: options Nov 26 10:53:09 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:09 test volumio[6834]: info: ------------------------------ 34ms Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand play took 25 milliseconds Nov 26 10:53:09 test volumio[6834]: info: ------------------------------ 7ms Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand status took 7 milliseconds Nov 26 10:53:09 test volumio[6834]: info: [1732614789466] [80s80s] Pushing the next song state: Salt-N-Pepa - Shoop Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music49/v4/03/75/b2/0375b26e-1466-4483-c849-c2af4117b815/source/600x600bb.jpg","name":"Salt-N-Pepa - Shoop","title":"Shoop","artist":"90s90s HipHop & Rap","album":"","streaming":true,"disableUiControls":true,"duration":"220","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 26 10:53:09 test volumio[6834]: verbose: CURRENT POSITION 0 Nov 26 10:53:09 test volumio[6834]: info: CoreStateMachine::syncState stateService play Nov 26 10:53:09 test volumio[6834]: info: CoreStateMachine::syncState currentStatus stop Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:09 test volumio[6834]: info: [1732614789470] [80s80s] PlayNextTrack API delay: 30 Nov 26 10:53:09 test volumio[6834]: info: [1732614789486] [80s80s] Setting timer to: 220000 milliseconds. Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 1 Nov 26 10:53:09 test volumio[6834]: info: ------------------------------ 37ms Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand status took 34 milliseconds Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand playlistinfo took 27 milliseconds Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:09 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:09 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:09 test volumio[6834]: info: ------------------------------ 43ms Nov 26 10:53:09 test volumio[6834]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 26 10:53:09 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:09 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:09 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:09 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:09 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:09 test volumio[6834]: info: ------------------------------ 45ms Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 7 milliseconds Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 6 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 5 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 4 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 3 milliseconds Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 5 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 20ms Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 19ms Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 18ms Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 16ms Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 15ms Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 18ms Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:11 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:11 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 4ms Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 3 milliseconds Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:11 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:11 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 8ms Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 7 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 6ms Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 4 milliseconds Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 4ms Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 3 milliseconds Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 16ms Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:11 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:11 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:11 test volumio[6834]: info: Nov 26 10:53:11 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 7ms Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 5 milliseconds Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 5ms Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand status took 4 milliseconds Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 30ms Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 26ms Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 24ms Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 26 10:53:11 test volumio[6834]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:11 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:11 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:11 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 20ms Nov 26 10:53:11 test volumio[6834]: info: ------------------------------ 18ms Nov 26 10:53:17 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 10:53:17 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 10:53:20 test volumio[6834]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 10:53:26 test volumio[6834]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Nov 26 10:53:26 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Nov 26 10:53:26 test sudo[7701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 26 10:53:26 test sudo[7701]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:53:26 test sudo[7701]: pam_unix(sudo:session): session closed for user root Nov 26 10:53:26 test volumio[6834]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 26 10:53:26 test volumio[6834]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Nov 26 10:53:26 test sudo[7704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Nov 26 10:53:26 test sudo[7704]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:53:26 test startx[6776]: xinit: connection to X server lost Nov 26 10:53:26 test startx[6776]: [1B blob data] Nov 26 10:53:26 test systemd[1]: Stopping Volumio Kiosk... Nov 26 10:53:27 test volumio[6834]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Nov 26 10:53:27 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Nov 26 10:53:36 test kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:102:pixelvalve-2] flip_done timed out Nov 26 10:53:37 test startx[6776]: waiting for X server to shut down .......... Nov 26 10:53:37 test startx[6776]: xinit: X server slow to shut down, sending KILL signal Nov 26 10:53:37 test startx[6776]: [1B blob data] Nov 26 10:53:41 test startx[6776]: waiting for server to die ... Nov 26 10:53:41 test startx[6776]: xinit: X server refuses to die Nov 26 10:53:41 test systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Nov 26 10:53:45 test volumio[6834]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Nov 26 10:53:45 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Nov 26 10:53:45 test sudo[7748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 26 10:53:45 test sudo[7748]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:53:45 test sudo[7748]: pam_unix(sudo:session): session closed for user root Nov 26 10:53:45 test volumio[6834]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 26 10:53:45 test volumio[6834]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Nov 26 10:53:45 test sudo[7751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Nov 26 10:53:45 test sudo[7751]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:53:47 test kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Nov 26 10:53:47 test kernel: vc4-drm gpu: [drm] *ERROR* Timed out waiting for commit Nov 26 10:53:48 test volumio[6834]: info: Nov 26 10:53:48 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:48 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:48 test volumio[6834]: info: Nov 26 10:53:48 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:48 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:48 test volumio[6834]: info: Nov 26 10:53:48 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:48 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:48 test volumio[6834]: info: Nov 26 10:53:48 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:48 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:48 test volumio[6834]: info: Nov 26 10:53:48 test volumio[6834]: ---------------------------- MPD announces system playlist update Nov 26 10:53:48 test volumio[6834]: info: Ignoring MPD Status Update Nov 26 10:53:48 test volumio[6834]: info: Nov 26 10:53:48 test volumio[6834]: ---------------------------- MPD announces state update: player Nov 26 10:53:48 test volumio[6834]: info: ControllerMpd::getState Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand status Nov 26 10:53:48 test volumio[6834]: info: ------------------------------ 7ms Nov 26 10:53:48 test volumio[6834]: info: sendMpdCommand status took 5 milliseconds Nov 26 10:53:48 test volumio[6834]: info: ------------------------------ 4ms Nov 26 10:53:48 test volumio[6834]: info: sendMpdCommand status took 4 milliseconds Nov 26 10:53:48 test volumio[6834]: info: ------------------------------ 3ms Nov 26 10:53:48 test volumio[6834]: info: sendMpdCommand status took 2 milliseconds Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::parseState Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 10:53:48 test volumio[6834]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 26 10:53:48 test volumio[6834]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 26 10:53:48 test volumio[6834]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:48 test volumio[6834]: verbose: ControllerMpd::parseTrackInfo Nov 26 10:53:48 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:48 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:48 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:48 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:48 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:48 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:48 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:48 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:48 test volumio[6834]: info: ControllerMpd::pushState Nov 26 10:53:48 test volumio[6834]: info: CoreCommandRouter::servicePushState Nov 26 10:53:48 test volumio[6834]: info: CorePlayQueue::getTrack 0 Nov 26 10:53:48 test volumio[6834]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 26 10:53:48 test volumio[6834]: info: ------------------------------ 12ms Nov 26 10:53:48 test volumio[6834]: info: ------------------------------ 10ms Nov 26 10:53:48 test volumio[6834]: info: ------------------------------ 8ms Nov 26 10:53:57 test kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Nov 26 10:53:57 test kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:102:pixelvalve-2] commit wait timed out Nov 26 10:54:07 test kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:102:pixelvalve-2] flip_done timed out Nov 26 10:54:12 test volumio[6834]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Nov 26 10:54:12 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Nov 26 10:54:12 test sudo[7854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 26 10:54:12 test sudo[7854]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:54:12 test sudo[7854]: pam_unix(sudo:session): session closed for user root Nov 26 10:54:12 test volumio[6834]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 26 10:54:12 test volumio[6834]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Nov 26 10:54:12 test sudo[7857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Nov 26 10:54:12 test sudo[7857]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:54:17 test kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Nov 26 10:54:17 test kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:102:pixelvalve-2] commit wait timed out Nov 26 10:54:28 test kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Nov 26 10:54:28 test kernel: vc4-drm gpu: [drm] *ERROR* [CONNECTOR:32:HDMI-A-1] commit wait timed out Nov 26 10:54:38 test kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Nov 26 10:54:38 test kernel: vc4-drm gpu: [drm] *ERROR* [PLANE:91:plane-3] commit wait timed out Nov 26 10:54:38 test volumio[6834]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Nov 26 10:54:38 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Nov 26 10:54:38 test sudo[7910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 26 10:54:38 test sudo[7910]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:54:38 test sudo[7910]: pam_unix(sudo:session): session closed for user root Nov 26 10:54:38 test volumio[6834]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 26 10:54:38 test volumio[6834]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Nov 26 10:54:38 test sudo[7913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Nov 26 10:54:38 test sudo[7913]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:54:44 test volumio[6834]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Nov 26 10:54:44 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Nov 26 10:54:44 test sudo[7919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 26 10:54:44 test sudo[7919]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:54:44 test sudo[7919]: pam_unix(sudo:session): session closed for user root Nov 26 10:54:44 test volumio[6834]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 26 10:54:44 test volumio[6834]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Nov 26 10:54:44 test sudo[7922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Nov 26 10:54:44 test sudo[7922]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:54:46 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 26 10:54:46 test volumio[6834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 26 10:54:48 test kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Nov 26 10:54:48 test kernel: vc4-drm gpu: [drm] *ERROR* Timed out waiting for commit Nov 26 10:54:48 test systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Nov 26 10:54:48 test systemd[1]: Stopped Volumio Kiosk. Nov 26 10:54:48 test systemd[1]: Started Volumio Kiosk. Nov 26 10:54:48 test sudo[7922]: pam_unix(sudo:session): session closed for user root Nov 26 10:54:48 test sudo[7857]: pam_unix(sudo:session): session closed for user root Nov 26 10:54:48 test sudo[7751]: pam_unix(sudo:session): session closed for user root Nov 26 10:54:48 test volumio[6834]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Nov 26 10:54:48 test sudo[7913]: pam_unix(sudo:session): session closed for user root Nov 26 10:54:48 test sudo[7704]: pam_unix(sudo:session): session closed for user root Nov 26 10:54:48 test volumio[6834]: info: touch_display: Volumio Kiosk restarted. Nov 26 10:54:48 test volumio[6834]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Nov 26 10:54:48 test volumio[6834]: info: touch_display: Volumio Kiosk restarted. Nov 26 10:54:48 test volumio[6834]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Nov 26 10:54:48 test volumio[6834]: info: touch_display: Volumio Kiosk restarted. Nov 26 10:54:48 test startx[8001]: X.Org X Server 1.20.4 Nov 26 10:54:48 test startx[8001]: X Protocol Version 11, Revision 0 Nov 26 10:54:48 test startx[8001]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Nov 26 10:54:48 test startx[8001]: Current Operating System: Linux test 6.6.56-v7l+ #1803 SMP Thu Oct 10 14:10:41 BST 2024 armv7l Nov 26 10:54:48 test startx[8001]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:640x480M@60D smsc95xx.macaddr=2C:CF:67:54:39:21 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=ed280c2b-b4b4-43e6-87d7-b67377eacab2 imgfile=/volumio_current.sqsh bootpart=UUID=FCA2-3F9A datapart=UUID=0367e456-8c2f-458a-8465-47f124ad9fdd 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 Nov 26 10:54:48 test startx[8001]: Build Date: 04 April 2023 07:50:56AM Nov 26 10:54:48 test startx[8001]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Nov 26 10:54:48 test startx[8001]: Current version of pixman: 0.36.0 Nov 26 10:54:48 test startx[8001]: Before reporting problems, check http://wiki.x.org Nov 26 10:54:48 test startx[8001]: to make sure that you have the latest version. Nov 26 10:54:48 test startx[8001]: Markers: (--) probed, (**) from config file, (==) default setting, Nov 26 10:54:48 test startx[8001]: (++) from command line, (!!) notice, (II) informational, Nov 26 10:54:48 test startx[8001]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Nov 26 10:54:48 test startx[8001]: (==) Log file: "/var/log/Xorg.2.log", Time: Tue Nov 26 10:54:48 2024 Nov 26 10:54:48 test startx[8001]: (==) Using config directory: "/etc/X11/xorg.conf.d" Nov 26 10:54:48 test startx[8001]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Nov 26 10:54:48 test volumio[6834]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Nov 26 10:54:48 test volumio[6834]: info: touch_display: Volumio Kiosk restarted. Nov 26 10:54:48 test volumio[6834]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Nov 26 10:54:48 test volumio[6834]: info: touch_display: Volumio Kiosk restarted. Nov 26 10:54:48 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:48 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:48 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:48 test volumio[6834]: info: touch_display: X display number found: Nov 26 10:54:48 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test kernel: broken atomic modeset userspace detected, disabling atomic Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test startx[8001]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:49 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: X display number found: 2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X2 Nov 26 10:54:50 test volumio[6834]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X2 Nov 26 10:54:50 test volumio[6834]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:54:50 test volumio[6834]: Error: connect EISCONN /tmp/.X11-unix/X2 Nov 26 10:54:50 test volumio[6834]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 26 10:54:50 test volumio[6834]: errno: -106, Nov 26 10:54:50 test volumio[6834]: code: 'EISCONN', Nov 26 10:54:50 test volumio[6834]: syscall: 'connect', Nov 26 10:54:50 test volumio[6834]: address: '/tmp/.X11-unix/X2' Nov 26 10:54:50 test volumio[6834]: } Nov 26 10:54:50 test volumio[6834]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:54:50 test sudo[8245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 10:53 Nov 26 10:54:50 test sudo[8245]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"