-- Logs begin at Wed 2024-04-24 14:17:09 UTC, end at Wed 2024-04-24 17:53:17 UTC. -- Apr 24 17:52:12 volumiobox volumio[5195]: info: CoreCommandRouter::volumioGetState Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreCommandRouter::volumioNext Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreStateMachine::next Apr 24 17:52:18 volumiobox volumio[5195]: info: ControllerMpd::next Apr 24 17:52:18 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand next Apr 24 17:52:18 volumiobox volumio[5195]: info: Apr 24 17:52:18 volumiobox volumio[5195]: ---------------------------- MPD announces system playlist update Apr 24 17:52:18 volumiobox volumio[5195]: info: Ignoring MPD Status Update Apr 24 17:52:18 volumiobox volumio[5195]: info: Apr 24 17:52:18 volumiobox volumio[5195]: ---------------------------- MPD announces state update: player Apr 24 17:52:18 volumiobox volumio[5195]: info: ControllerMpd::getState Apr 24 17:52:18 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:52:18 volumiobox volumio[5195]: error: updateQueue error: null Apr 24 17:52:18 volumiobox volumio[5195]: info: ------------------------------ 3ms Apr 24 17:52:18 volumiobox volumio[5195]: verbose: ControllerMpd::parseState Apr 24 17:52:18 volumiobox volumio[5195]: info: ControllerMpd::pushState Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreCommandRouter::servicePushState Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:18 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 6 Apr 24 17:52:18 volumiobox volumio[5195]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 24 17:52:18 volumiobox volumio[5195]: verbose: CURRENT POSITION 6 Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreStateMachine::syncState stateService stop Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreStateMachine::play index undefined Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:18 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 18 Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:18 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 18 Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:52:18 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 18 Apr 24 17:52:18 volumiobox volumio[5195]: info: [1713981138990] ControllerUPNPBrowser::clearAddPlayTrack Apr 24 17:52:18 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:52:18 volumiobox volumio[5195]: info: ------------------------------ 17ms Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:18 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 18 Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:18 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 17:52:19 volumiobox volumio[5195]: info: Apr 24 17:52:19 volumiobox volumio[5195]: ---------------------------- MPD announces system playlist update Apr 24 17:52:19 volumiobox volumio[5195]: info: Ignoring MPD Status Update Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand load "http://10.31.1.254:8200/MediaItems/1262.m4a" Apr 24 17:52:19 volumiobox volumio[5195]: error: updateQueue error: null Apr 24 17:52:19 volumiobox volumio[5195]: info: ------------------------------ 3ms Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand add "http://10.31.1.254:8200/MediaItems/1262.m4a" Apr 24 17:52:19 volumiobox volumio[5195]: info: Apr 24 17:52:19 volumiobox volumio[5195]: ---------------------------- MPD announces system playlist update Apr 24 17:52:19 volumiobox volumio[5195]: info: Ignoring MPD Status Update Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand play Apr 24 17:52:19 volumiobox volumio[5195]: info: ------------------------------ 2ms Apr 24 17:52:19 volumiobox volumio[5195]: info: Apr 24 17:52:19 volumiobox volumio[5195]: ---------------------------- MPD announces state update: player Apr 24 17:52:19 volumiobox volumio[5195]: info: ControllerMpd::getState Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:52:19 volumiobox volumio[5195]: info: Apr 24 17:52:19 volumiobox volumio[5195]: ---------------------------- MPD announces state update: player Apr 24 17:52:19 volumiobox volumio[5195]: info: ControllerMpd::getState Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::parseState Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::parseState Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:52:19 volumiobox volumio[5195]: info: ControllerMpd::pushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreCommandRouter::servicePushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 18 Apr 24 17:52:19 volumiobox volumio[5195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"260 Kbps","isStreaming":false,"title":"1262.m4a","artist":null,"album":null,"uri":"http://10.31.1.254:8200/MediaItems/1262.m4a","trackType":"m4a"} Apr 24 17:52:19 volumiobox volumio[5195]: verbose: CURRENT POSITION 18 Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreStateMachine::syncState stateService play Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:19 volumiobox volumio[5195]: info: ------------------------------ 21ms Apr 24 17:52:19 volumiobox volumio[5195]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:52:19 volumiobox volumio[5195]: info: ControllerMpd::pushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreCommandRouter::servicePushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 18 Apr 24 17:52:19 volumiobox volumio[5195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":216,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"260 Kbps","isStreaming":false,"title":"1262.m4a","artist":null,"album":null,"uri":"http://10.31.1.254:8200/MediaItems/1262.m4a","trackType":"m4a"} Apr 24 17:52:19 volumiobox volumio[5195]: verbose: CURRENT POSITION 18 Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreStateMachine::syncState stateService play Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:52:19 volumiobox volumio[5195]: info: Received an update from plugin. extracting info from payload Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:19 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:19 volumiobox volumio[5195]: info: ------------------------------ 43ms Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::volumioNext Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::next Apr 24 17:52:23 volumiobox volumio[5195]: info: ControllerMpd::next Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand next Apr 24 17:52:23 volumiobox volumio[5195]: info: Apr 24 17:52:23 volumiobox volumio[5195]: ---------------------------- MPD announces system playlist update Apr 24 17:52:23 volumiobox volumio[5195]: info: Ignoring MPD Status Update Apr 24 17:52:23 volumiobox volumio[5195]: info: Apr 24 17:52:23 volumiobox volumio[5195]: ---------------------------- MPD announces state update: player Apr 24 17:52:23 volumiobox volumio[5195]: info: ControllerMpd::getState Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:52:23 volumiobox volumio[5195]: error: updateQueue error: null Apr 24 17:52:23 volumiobox volumio[5195]: info: ------------------------------ 9ms Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::parseState Apr 24 17:52:23 volumiobox volumio[5195]: info: ControllerMpd::pushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::servicePushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:23 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 18 Apr 24 17:52:23 volumiobox volumio[5195]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 24 17:52:23 volumiobox volumio[5195]: verbose: CURRENT POSITION 18 Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::syncState stateService stop Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::play index undefined Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 14 Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:23 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 14 Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:52:23 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 14 Apr 24 17:52:23 volumiobox volumio[5195]: info: [1713981143687] ControllerUPNPBrowser::clearAddPlayTrack Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:52:23 volumiobox volumio[5195]: info: ------------------------------ 37ms Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 14 Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 17:52:23 volumiobox volumio[5195]: info: Apr 24 17:52:23 volumiobox volumio[5195]: ---------------------------- MPD announces system playlist update Apr 24 17:52:23 volumiobox volumio[5195]: info: Ignoring MPD Status Update Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand load "http://10.31.1.254:8200/MediaItems/1256.m4a" Apr 24 17:52:23 volumiobox volumio[5195]: error: updateQueue error: null Apr 24 17:52:23 volumiobox volumio[5195]: info: ------------------------------ 6ms Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand add "http://10.31.1.254:8200/MediaItems/1256.m4a" Apr 24 17:52:23 volumiobox volumio[5195]: info: Apr 24 17:52:23 volumiobox volumio[5195]: ---------------------------- MPD announces system playlist update Apr 24 17:52:23 volumiobox volumio[5195]: info: Ignoring MPD Status Update Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand play Apr 24 17:52:23 volumiobox volumio[5195]: info: ------------------------------ 2ms Apr 24 17:52:23 volumiobox volumio[5195]: info: Apr 24 17:52:23 volumiobox volumio[5195]: ---------------------------- MPD announces state update: player Apr 24 17:52:23 volumiobox volumio[5195]: info: ControllerMpd::getState Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:52:23 volumiobox volumio[5195]: info: Apr 24 17:52:23 volumiobox volumio[5195]: ---------------------------- MPD announces state update: player Apr 24 17:52:23 volumiobox volumio[5195]: info: ControllerMpd::getState Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::parseState Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::parseState Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:52:23 volumiobox volumio[5195]: info: ControllerMpd::pushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::servicePushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 14 Apr 24 17:52:23 volumiobox volumio[5195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":255,"duration":224,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"261 Kbps","isStreaming":false,"title":"1256.m4a","artist":null,"album":null,"uri":"http://10.31.1.254:8200/MediaItems/1256.m4a","trackType":"m4a"} Apr 24 17:52:23 volumiobox volumio[5195]: verbose: CURRENT POSITION 14 Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::syncState stateService play Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:23 volumiobox volumio[5195]: info: ------------------------------ 22ms Apr 24 17:52:23 volumiobox volumio[5195]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:52:23 volumiobox volumio[5195]: info: ControllerMpd::pushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::servicePushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 14 Apr 24 17:52:23 volumiobox volumio[5195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":224,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"261 Kbps","isStreaming":false,"title":"1256.m4a","artist":null,"album":null,"uri":"http://10.31.1.254:8200/MediaItems/1256.m4a","trackType":"m4a"} Apr 24 17:52:23 volumiobox volumio[5195]: verbose: CURRENT POSITION 14 Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::syncState stateService play Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:52:23 volumiobox volumio[5195]: info: Received an update from plugin. extracting info from payload Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:23 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:23 volumiobox volumio[5195]: info: ------------------------------ 38ms Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::volumioNext Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::next Apr 24 17:52:28 volumiobox volumio[5195]: info: ControllerMpd::next Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand next Apr 24 17:52:28 volumiobox volumio[5195]: info: Apr 24 17:52:28 volumiobox volumio[5195]: ---------------------------- MPD announces system playlist update Apr 24 17:52:28 volumiobox volumio[5195]: info: Ignoring MPD Status Update Apr 24 17:52:28 volumiobox volumio[5195]: info: Apr 24 17:52:28 volumiobox volumio[5195]: ---------------------------- MPD announces state update: player Apr 24 17:52:28 volumiobox volumio[5195]: info: ControllerMpd::getState Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:52:28 volumiobox volumio[5195]: error: updateQueue error: null Apr 24 17:52:28 volumiobox volumio[5195]: info: ------------------------------ 3ms Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::parseState Apr 24 17:52:28 volumiobox volumio[5195]: info: ControllerMpd::pushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::servicePushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:28 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 14 Apr 24 17:52:28 volumiobox volumio[5195]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 24 17:52:28 volumiobox volumio[5195]: verbose: CURRENT POSITION 14 Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::syncState stateService stop Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::play index undefined Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 10 Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:28 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 10 Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:52:28 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 10 Apr 24 17:52:28 volumiobox volumio[5195]: info: [1713981148752] ControllerUPNPBrowser::clearAddPlayTrack Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:52:28 volumiobox volumio[5195]: info: ------------------------------ 24ms Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 10 Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 17:52:28 volumiobox volumio[5195]: info: Apr 24 17:52:28 volumiobox volumio[5195]: ---------------------------- MPD announces system playlist update Apr 24 17:52:28 volumiobox volumio[5195]: info: Ignoring MPD Status Update Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand load "http://10.31.1.254:8200/MediaItems/1250.m4a" Apr 24 17:52:28 volumiobox volumio[5195]: error: updateQueue error: null Apr 24 17:52:28 volumiobox volumio[5195]: info: ------------------------------ 5ms Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand add "http://10.31.1.254:8200/MediaItems/1250.m4a" Apr 24 17:52:28 volumiobox volumio[5195]: info: Apr 24 17:52:28 volumiobox volumio[5195]: ---------------------------- MPD announces system playlist update Apr 24 17:52:28 volumiobox volumio[5195]: info: Ignoring MPD Status Update Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand play Apr 24 17:52:28 volumiobox volumio[5195]: info: ------------------------------ 3ms Apr 24 17:52:28 volumiobox volumio[5195]: info: Apr 24 17:52:28 volumiobox volumio[5195]: ---------------------------- MPD announces state update: player Apr 24 17:52:28 volumiobox volumio[5195]: info: ControllerMpd::getState Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:52:28 volumiobox volumio[5195]: info: Apr 24 17:52:28 volumiobox volumio[5195]: ---------------------------- MPD announces state update: player Apr 24 17:52:28 volumiobox volumio[5195]: info: ControllerMpd::getState Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::parseState Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::parseState Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:52:28 volumiobox volumio[5195]: info: ControllerMpd::pushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::servicePushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 10 Apr 24 17:52:28 volumiobox volumio[5195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":262,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"262 Kbps","isStreaming":false,"title":"1250.m4a","artist":null,"album":null,"uri":"http://10.31.1.254:8200/MediaItems/1250.m4a","trackType":"m4a"} Apr 24 17:52:28 volumiobox volumio[5195]: verbose: CURRENT POSITION 10 Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::syncState stateService play Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:28 volumiobox volumio[5195]: info: ------------------------------ 25ms Apr 24 17:52:28 volumiobox volumio[5195]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:52:28 volumiobox volumio[5195]: info: ControllerMpd::pushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::servicePushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CorePlayQueue::getTrack 10 Apr 24 17:52:28 volumiobox volumio[5195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":262,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"262 Kbps","isStreaming":false,"title":"1250.m4a","artist":null,"album":null,"uri":"http://10.31.1.254:8200/MediaItems/1250.m4a","trackType":"m4a"} Apr 24 17:52:28 volumiobox volumio[5195]: verbose: CURRENT POSITION 10 Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::syncState stateService play Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:52:28 volumiobox volumio[5195]: info: Received an update from plugin. extracting info from payload Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreStateMachine::pushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::volumioPushState Apr 24 17:52:28 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:52:28 volumiobox volumio[5195]: info: ------------------------------ 40ms Apr 24 17:52:39 volumiobox sudo[14719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 24 17:52:39 volumiobox sudo[14719]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:52:39 volumiobox sudo[14719]: pam_unix(sudo:session): session closed for user root Apr 24 17:52:39 volumiobox sudo[14722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 17:52:39 volumiobox sudo[14722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:52:39 volumiobox sudo[14722]: pam_unix(sudo:session): session closed for user root Apr 24 17:52:40 volumiobox sudo[14728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 24 17:52:40 volumiobox sudo[14728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:52:40 volumiobox sudo[14728]: pam_unix(sudo:session): session closed for user root Apr 24 17:52:40 volumiobox sudo[14731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 17:52:40 volumiobox sudo[14731]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:52:40 volumiobox sudo[14731]: pam_unix(sudo:session): session closed for user root Apr 24 17:52:40 volumiobox volumio[5195]: info: CoreCommandRouter::volumioGetState Apr 24 17:52:41 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 24 17:52:41 volumiobox volumio[5195]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:52:41 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:52:41 volumiobox volumio[5195]: info: Listing playlists Apr 24 17:52:41 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 24 17:52:41 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 24 17:52:41 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 24 17:52:41 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 24 17:52:43 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:52:44 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:53:09 volumiobox volumio[5195]: info: Enabling plugin touch_display Apr 24 17:53:09 volumiobox volumio[5195]: info: Loading plugin "touch_display"... Apr 24 17:53:09 volumiobox volumio[5195]: info: Applying required configuration parameters for plugin touch_display Apr 24 17:53:09 volumiobox volumio[5195]: info: PLUGIN START: touch_display Apr 24 17:53:09 volumiobox volumio[5195]: info: Loading i18n strings for locale pt Apr 24 17:53:09 volumiobox sudo[14829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 24 17:53:09 volumiobox sudo[14829]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:53:09 volumiobox systemd[1]: Reloading. Apr 24 17:53:10 volumiobox systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Apr 24 17:53:10 volumiobox systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Apr 24 17:53:10 volumiobox systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Apr 24 17:53:10 volumiobox systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Apr 24 17:53:10 volumiobox sudo[14829]: pam_unix(sudo:session): session closed for user root Apr 24 17:53:10 volumiobox volumio[5195]: info: touch_display: systemctl daemon-reload succeeded. Apr 24 17:53:10 volumiobox sudo[14843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 24 17:53:10 volumiobox sudo[14843]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:53:10 volumiobox systemd[1]: Starting Volumio Kiosk... Apr 24 17:53:10 volumiobox systemd[1]: Started Volumio Kiosk. Apr 24 17:53:10 volumiobox systemd[14845]: Failed at step EXEC spawning /usr/bin/startx: No such file or directory Apr 24 17:53:10 volumiobox systemd[1]: volumio-kiosk.service: main process exited, code=exited, status=203/EXEC Apr 24 17:53:10 volumiobox systemd[1]: Unit volumio-kiosk.service entered failed state. Apr 24 17:53:10 volumiobox sudo[14843]: pam_unix(sudo:session): session closed for user root Apr 24 17:53:10 volumiobox volumio[5195]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 24 17:53:10 volumiobox volumio[5195]: info: touch_display: Volumio Kiosk started Apr 24 17:53:10 volumiobox volumio[5195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:53:10 volumiobox volumio[5195]: info: CoreCommandRouter::volumioGetState Apr 24 17:53:10 volumiobox volumio[5195]: info: Done. Apr 24 17:53:10 volumiobox volumio[5195]: info: CoreCommandRouter::volumioGetState Apr 24 17:53:10 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:10 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:10 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:10 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:10 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:11 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:11 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:11 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:11 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:11 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:11 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:11 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:11 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:11 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:12 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:12 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:12 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:12 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:12 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:12 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:12 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:12 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:13 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:13 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:13 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:13 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:13 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:13 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:13 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:13 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:13 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:14 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:14 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:14 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:14 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:14 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:14 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:14 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:14 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:15 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:15 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:15 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:15 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:15 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:15 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:15 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:15 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:15 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:16 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:16 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:16 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:16 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:16 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:16 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:16 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:16 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:17 volumiobox volumio[5195]: error: touch_display: Xserver unix domain socket cannot be determined. Apr 24 17:53:17 volumiobox sudo[14984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Apr 24 17:53:17 volumiobox sudo[14984]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:53:17 volumiobox systemd[1]: Stopped Volumio Kiosk. Apr 24 17:53:17 volumiobox sudo[14984]: pam_unix(sudo:session): session closed for user root Apr 24 17:53:17 volumiobox volumio[5195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:53:17 volumiobox volumio[5195]: { Error: connect ENOENT /tmp/.X11-unix/X Apr 24 17:53:17 volumiobox volumio[5195]: at Object._errnoException (util.js:1022:11) Apr 24 17:53:17 volumiobox volumio[5195]: at _exceptionWithHostPort (util.js:1044:20) Apr 24 17:53:17 volumiobox volumio[5195]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 24 17:53:17 volumiobox volumio[5195]: code: 'ENOENT', Apr 24 17:53:17 volumiobox volumio[5195]: errno: 'ENOENT', Apr 24 17:53:17 volumiobox volumio[5195]: syscall: 'connect', Apr 24 17:53:17 volumiobox volumio[5195]: address: '/tmp/.X11-unix/X' } Apr 24 17:53:17 volumiobox volumio[5195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:53:17 volumiobox sudo[14996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-24 17:52 Apr 24 17:53:17 volumiobox sudo[14996]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="493b3f2063c664b52681092d76718ef5fb070910" VOLUMIO_FE_VERSION="4917998f86709e30d6499627aa39df0350cb55f8" VOLUMIO_BE_VERSION="d9435b7a968fdb32f8774ce87d86f5f44a90fb66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun Aug 2 23:36:23 CEST 2020" VOLUMIO_VERSION="2.806" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="721ce12033fe994c37bd358dd68cf9f9"