Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" Dec 16 22:41:00 volumio volumio[1201]: error: updateQueue error: null Dec 16 22:41:00 volumio volumio[1201]: error: updateQueue error: null Dec 16 22:41:00 volumio volumio[1201]: info: Dec 16 22:41:00 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:00 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:00 volumio volumio[1201]: info: ------------------------------ 1041ms Dec 16 22:41:00 volumio volumio[1201]: info: ------------------------------ 1040ms Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" took 24 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand play Dec 16 22:41:00 volumio volumio[1201]: info: Dec 16 22:41:00 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:00 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:00 volumio volumio[1201]: info: Dec 16 22:41:00 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:00 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:00 volumio volumio[1201]: info: ------------------------------ 73ms Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand play took 70 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: ------------------------------ 69ms Dec 16 22:41:00 volumio volumio[1201]: info: ------------------------------ 68ms Dec 16 22:41:00 volumio volumio[1201]: info: Dec 16 22:41:00 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:00 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:00 volumio volumio[1201]: info: Dec 16 22:41:00 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:00 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:00 volumio volumio[1201]: info: Dec 16 22:41:00 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:00 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:00 volumio volumio[1201]: info: Dec 16 22:41:00 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:00 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:00 volumio volumio[1201]: info: Dec 16 22:41:00 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:00 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:00 volumio volumio[1201]: info: Dec 16 22:41:00 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand status took 51 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand status took 51 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand status took 9 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand status took 7 milliseconds Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand status took 72 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand status took 70 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 16 22:41:00 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 63 milliseconds Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:00 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:00 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:00 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:00 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:00 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:00 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus stop Dec 16 22:41:00 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:00 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:00 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:00 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:00 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:00 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:00 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:00 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:00 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:00 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:00 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:00 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:00 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:00 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:00 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:00 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:00 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:01 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:01 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:01 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:01 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:01 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:01 volumio volumio[1201]: info: ------------------------------ 515ms Dec 16 22:41:01 volumio volumio[1201]: info: ------------------------------ 812ms Dec 16 22:41:01 volumio volumio[1201]: info: ------------------------------ 864ms Dec 16 22:41:01 volumio volumio[1201]: info: ------------------------------ 823ms Dec 16 22:41:01 volumio volumio[1201]: info: Dec 16 22:41:01 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:01 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:01 volumio volumio[1201]: info: Dec 16 22:41:01 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:01 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:01 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:01 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 786 milliseconds Dec 16 22:41:01 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 786 milliseconds Dec 16 22:41:01 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:01 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:01 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:01 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:01 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:01 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:01 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:01 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:01 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:01 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:01 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:01 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:01 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:01 volumio volumio[1201]: info: ------------------------------ 1321ms Dec 16 22:41:02 volumio volumio[1201]: info: ------------------------------ 1338ms Dec 16 22:41:02 volumio volumio[1201]: info: Dec 16 22:41:02 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:02 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:02 volumio volumio[1201]: info: Dec 16 22:41:02 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:02 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:02 volumio volumio[1201]: info: Dec 16 22:41:02 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:02 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:02 volumio volumio[1201]: info: Dec 16 22:41:02 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:02 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:02 volumio volumio[1201]: info: ------------------------------ 876ms Dec 16 22:41:02 volumio volumio[1201]: info: sendMpdCommand status took 873 milliseconds Dec 16 22:41:02 volumio volumio[1201]: info: ------------------------------ 450ms Dec 16 22:41:02 volumio volumio[1201]: info: sendMpdCommand status took 429 milliseconds Dec 16 22:41:02 volumio volumio[1201]: info: ------------------------------ 428ms Dec 16 22:41:02 volumio volumio[1201]: info: sendMpdCommand status took 400 milliseconds Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:02 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 16 22:41:02 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 16 22:41:02 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:02 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:02 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:02 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:02 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1662,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:02 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:02 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:02 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:02 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:02 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2075,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:02 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:02 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:02 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:02 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:02 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2075,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:02 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:02 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:02 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:03 volumio volumio[1201]: info: ------------------------------ 1487ms Dec 16 22:41:03 volumio volumio[1201]: info: ------------------------------ 1036ms Dec 16 22:41:03 volumio volumio[1201]: info: ------------------------------ 1008ms Dec 16 22:41:03 volumio volumio[1201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 16 22:41:03 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:03 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:03 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:03 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:03 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:03 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:07 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 22:41:07 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 22:41:08 volumio sudo[2212]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:41:08 volumio sudo[2212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:41:08 volumio sudo[2212]: pam_unix(sudo:session): session closed for user root Dec 16 22:41:08 volumio sudo[2214]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:41:08 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:41:08 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root Dec 16 22:41:08 volumio volumio[1201]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.11 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 16 22:41:09 volumio sudo[2220]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:41:09 volumio sudo[2220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:41:09 volumio sudo[2220]: pam_unix(sudo:session): session closed for user root Dec 16 22:41:09 volumio sudo[2222]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:41:09 volumio sudo[2222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:41:09 volumio sudo[2222]: pam_unix(sudo:session): session closed for user root Dec 16 22:41:09 volumio volumio[1201]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.11 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 16 22:41:09 volumio volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 16 22:41:09 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 22:41:09 volumio volumio[1201]: info: Listing playlists Dec 16 22:41:09 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 22:41:09 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 22:41:09 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 22:41:09 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 22:41:12 volumio volumio[1201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 22:41:12 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:41:12 volumio volumio[1201]: info: CoreCommandRouter::volumioGetQueue Dec 16 22:41:12 volumio volumio[1201]: info: CoreStateMachine::getQueue Dec 16 22:41:12 volumio volumio[1201]: info: CorePlayQueue::getQueue Dec 16 22:41:27 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 22:41:27 volumio volumio[1201]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 16 22:41:27 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 16 22:41:27 volumio volumio[1201]: info: Received Get System Version Dec 16 22:41:27 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:41:27 volumio volumio[1201]: info: Received Get System Info Dec 16 22:41:27 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 22:41:27 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:41:27 volumio volumio[1201]: info: Discovery: Getting this device information Dec 16 22:41:27 volumio volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 16 22:41:27 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:41:31 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 22:41:31 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 22:41:32 volumio sudo[2307]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:41:32 volumio sudo[2307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:41:32 volumio sudo[2309]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:41:32 volumio sudo[2309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:41:32 volumio sudo[2307]: pam_unix(sudo:session): session closed for user root Dec 16 22:41:32 volumio sudo[2309]: pam_unix(sudo:session): session closed for user root Dec 16 22:41:32 volumio volumio[1201]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.11 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Dec 16 22:41:33 volumio sudo[2329]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 22:41:33 volumio sudo[2329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:41:33 volumio sudo[2329]: pam_unix(sudo:session): session closed for user root Dec 16 22:41:33 volumio sudo[2331]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 22:41:33 volumio sudo[2331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:41:33 volumio sudo[2331]: pam_unix(sudo:session): session closed for user root Dec 16 22:41:33 volumio volumio[1201]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.11 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 22:41:33 volumio volumio[1201]: info: Listing playlists Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 22:41:33 volumio volumio[1201]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 16 22:41:33 volumio volumio[1201]: info: Received Get System Version Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:41:33 volumio volumio[1201]: info: Received Get System Info Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:41:33 volumio volumio[1201]: info: Discovery: Getting this device information Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 16 22:41:33 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:41:37 volumio volumio[1201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 22:41:37 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 22:41:42 volumio volumio[1201]: info: CoreCommandRouter::volumioGetQueue Dec 16 22:41:42 volumio volumio[1201]: info: CoreStateMachine::getQueue Dec 16 22:41:42 volumio volumio[1201]: info: CorePlayQueue::getQueue Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::volumioPlay Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::play index 0 Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::stop Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::stPlaybackTimer Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::updateTrackBlock Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrackBlock Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::serviceStop Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::serviceStop Dec 16 22:41:47 volumio volumio[1201]: info: [1765892507382] ControllerWebradio::stop Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 22:41:47 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:47 volumio volumio[1201]: info: Dec 16 22:41:47 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:47 volumio volumio[1201]: info: sendMpdCommand stop took 111 milliseconds Dec 16 22:41:47 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::play index undefined Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::startPlaybackTimer Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: [1765892507549] ControllerWebradio::clearAddPlayTrack Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 22:41:47 volumio volumio[1201]: info: sendMpdCommand status took 57 milliseconds Dec 16 22:41:47 volumio volumio[1201]: info: sendMpdCommand stop took 2 milliseconds Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 22:41:47 volumio volumio[1201]: info: Dec 16 22:41:47 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:47 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:47 volumio volumio[1201]: info: Dec 16 22:41:47 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:47 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:47 volumio volumio[1201]: info: Dec 16 22:41:47 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:47 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:47 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 46 milliseconds Dec 16 22:41:47 volumio volumio[1201]: info: sendMpdCommand clear took 47 milliseconds Dec 16 22:41:47 volumio volumio[1201]: info: sendMpdCommand status took 45 milliseconds Dec 16 22:41:47 volumio volumio[1201]: info: sendMpdCommand status took 44 milliseconds Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand load "http://listen.jazz88.org/ksds.mp3" Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:47 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:47 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 16 22:41:47 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 16 22:41:47 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:47 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:47 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:47 volumio volumio[1201]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 16 22:41:47 volumio volumio[1201]: info: ------------------------------ 444ms Dec 16 22:41:47 volumio volumio[1201]: info: ------------------------------ 383ms Dec 16 22:41:47 volumio volumio[1201]: info: ------------------------------ 431ms Dec 16 22:41:47 volumio volumio[1201]: info: Dec 16 22:41:47 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:47 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:47 volumio volumio[1201]: info: Dec 16 22:41:47 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:47 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:47 volumio volumio[1201]: error: updateQueue error: null Dec 16 22:41:47 volumio volumio[1201]: info: ------------------------------ 395ms Dec 16 22:41:48 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:48 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:48 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:48 volumio volumio[1201]: error: updateQueue error: null Dec 16 22:41:48 volumio volumio[1201]: error: updateQueue error: null Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" Dec 16 22:41:48 volumio volumio[1201]: info: ------------------------------ 157ms Dec 16 22:41:48 volumio volumio[1201]: info: ------------------------------ 156ms Dec 16 22:41:48 volumio volumio[1201]: info: Dec 16 22:41:48 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:48 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" took 45 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand play Dec 16 22:41:48 volumio volumio[1201]: info: Dec 16 22:41:48 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:48 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:48 volumio volumio[1201]: info: Dec 16 22:41:48 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:48 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:48 volumio volumio[1201]: info: ------------------------------ 48ms Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand play took 48 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: ------------------------------ 47ms Dec 16 22:41:48 volumio volumio[1201]: info: ------------------------------ 45ms Dec 16 22:41:48 volumio volumio[1201]: info: Dec 16 22:41:48 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:48 volumio volumio[1201]: info: Dec 16 22:41:48 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:48 volumio volumio[1201]: info: Dec 16 22:41:48 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:48 volumio volumio[1201]: info: Dec 16 22:41:48 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:48 volumio volumio[1201]: info: Dec 16 22:41:48 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:48 volumio volumio[1201]: info: Dec 16 22:41:48 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand status took 43 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand status took 42 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand status took 42 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand status took 45 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand status took 44 milliseconds Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand status took 47 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 22:41:48 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:48 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:48 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:48 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:48 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus stop Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:48 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:48 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:48 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:48 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:48 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:48 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:48 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:48 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:48 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:48 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:48 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:48 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:48 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:48 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:48 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:48 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:48 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:48 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:48 volumio volumio[1201]: info: ------------------------------ 489ms Dec 16 22:41:49 volumio volumio[1201]: info: ------------------------------ 1102ms Dec 16 22:41:49 volumio volumio[1201]: info: ------------------------------ 1152ms Dec 16 22:41:49 volumio volumio[1201]: info: ------------------------------ 1152ms Dec 16 22:41:49 volumio volumio[1201]: info: ------------------------------ 1130ms Dec 16 22:41:49 volumio volumio[1201]: info: Dec 16 22:41:49 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:49 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:49 volumio volumio[1201]: info: Dec 16 22:41:49 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:49 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:49 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:49 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 1127 milliseconds Dec 16 22:41:49 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:49 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:49 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:49 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:49 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:49 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:49 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:49 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:49 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:49 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:49 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:49 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:49 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:49 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:49 volumio volumio[1201]: info: ------------------------------ 1392ms Dec 16 22:41:49 volumio volumio[1201]: info: Dec 16 22:41:49 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:49 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:49 volumio volumio[1201]: info: Dec 16 22:41:49 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:49 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:49 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:50 volumio volumio[1201]: info: Dec 16 22:41:50 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:50 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:50 volumio volumio[1201]: info: Dec 16 22:41:50 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:50 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: info: CoreCommandRouter::volumioPlay Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::play index 0 Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::stop Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::stPlaybackTimer Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::updateTrackBlock Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrackBlock Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:50 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:50 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::serviceStop Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:50 volumio volumio[1201]: info: CoreCommandRouter::serviceStop Dec 16 22:41:50 volumio volumio[1201]: info: [1765892510396] ControllerWebradio::stop Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 22:41:50 volumio volumio[1201]: info: Dec 16 22:41:50 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:50 volumio volumio[1201]: info: ------------------------------ 772ms Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand status took 770 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: ------------------------------ 505ms Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand status took 504 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: ------------------------------ 492ms Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand status took 490 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand stop took 107 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::play index undefined Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::startPlaybackTimer Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:50 volumio volumio[1201]: info: [1765892510531] ControllerWebradio::clearAddPlayTrack Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand stop Dec 16 22:41:50 volumio volumio[1201]: info: Dec 16 22:41:50 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:50 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:50 volumio volumio[1201]: info: Dec 16 22:41:50 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:50 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand status took 105 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 104 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 105 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 105 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand stop took 77 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand status took 77 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand status took 54 milliseconds Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:50 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:50 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:50 volumio volumio[1201]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 16 22:41:50 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:50 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:50 volumio volumio[1201]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 16 22:41:50 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:50 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:50 volumio volumio[1201]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 16 22:41:50 volumio volumio[1201]: info: ------------------------------ 933ms Dec 16 22:41:50 volumio volumio[1201]: info: ------------------------------ 667ms Dec 16 22:41:50 volumio volumio[1201]: info: ------------------------------ 652ms Dec 16 22:41:50 volumio volumio[1201]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 16 22:41:50 volumio volumio[1201]: info: Dec 16 22:41:50 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:50 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 143 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand clear took 142 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 142 milliseconds Dec 16 22:41:50 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 141 milliseconds Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand load "http://listen.jazz88.org/ksds.mp3" Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:50 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:50 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:50 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:50 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:50 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:50 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:50 volumio volumio[1201]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 16 22:41:50 volumio volumio[1201]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 16 22:41:50 volumio volumio[1201]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 16 22:41:50 volumio volumio[1201]: info: ------------------------------ 402ms Dec 16 22:41:50 volumio volumio[1201]: info: ------------------------------ 372ms Dec 16 22:41:50 volumio volumio[1201]: info: ------------------------------ 349ms Dec 16 22:41:50 volumio volumio[1201]: info: Dec 16 22:41:50 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:50 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:50 volumio volumio[1201]: info: Dec 16 22:41:50 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:50 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:50 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:51 volumio volumio[1201]: error: updateQueue error: null Dec 16 22:41:51 volumio volumio[1201]: info: ------------------------------ 265ms Dec 16 22:41:51 volumio volumio[1201]: error: updateQueue error: null Dec 16 22:41:51 volumio volumio[1201]: error: updateQueue error: null Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" Dec 16 22:41:51 volumio volumio[1201]: info: ------------------------------ 297ms Dec 16 22:41:51 volumio volumio[1201]: info: ------------------------------ 297ms Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:51 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" took 14 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand play Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:51 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:51 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:51 volumio volumio[1201]: info: ------------------------------ 40ms Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand play took 5 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: ------------------------------ 4ms Dec 16 22:41:51 volumio volumio[1201]: info: ------------------------------ 37ms Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 22:41:51 volumio volumio[1201]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 16 22:41:51 volumio volumio[1201]: info: Received Get System Version Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 22:41:51 volumio volumio[1201]: info: Received Get System Info Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:41:51 volumio volumio[1201]: info: Discovery: Getting this device information Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand status took 22 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand status took 7 milliseconds Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand status took 56 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand status took 56 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand status took 30 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand status took 5 milliseconds Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:51 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:51 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:51 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:51 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:51 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus stop Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:51 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:51 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:51 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:51 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:51 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:51 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:51 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:51 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:51 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:51 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:51 volumio volumio[1201]: info: ------------------------------ 199ms Dec 16 22:41:51 volumio volumio[1201]: info: ------------------------------ 338ms Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:51 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:51 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:51 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces system playlist update Dec 16 22:41:51 volumio volumio[1201]: info: Ignoring MPD Status Update Dec 16 22:41:51 volumio volumio[1201]: info: Dec 16 22:41:51 volumio volumio[1201]: ---------------------------- MPD announces state update: player Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 342 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 343 milliseconds Dec 16 22:41:51 volumio volumio[1201]: info: ControllerMpd::getState Dec 16 22:41:52 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand status Dec 16 22:41:52 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 378 milliseconds Dec 16 22:41:52 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 379 milliseconds Dec 16 22:41:52 volumio volumio[1201]: info: ------------------------------ 107ms Dec 16 22:41:52 volumio volumio[1201]: info: sendMpdCommand status took 106 milliseconds Dec 16 22:41:52 volumio volumio[1201]: info: ------------------------------ 55ms Dec 16 22:41:52 volumio volumio[1201]: info: sendMpdCommand status took 54 milliseconds Dec 16 22:41:52 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:52 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:52 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:52 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:52 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:52 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:52 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:52 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:52 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:52 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:52 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:52 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:52 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:52 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:52 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:52 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:52 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:52 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:52 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:52 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:52 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:52 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:52 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:52 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:52 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:52 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:52 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:52 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:52 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:52 volumio volumio[1201]: info: ------------------------------ 1171ms Dec 16 22:41:52 volumio volumio[1201]: info: ------------------------------ 1184ms Dec 16 22:41:52 volumio volumio[1201]: info: ------------------------------ 1159ms Dec 16 22:41:52 volumio volumio[1201]: info: ------------------------------ 1136ms Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:52 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:53 volumio volumio[1201]: info: ------------------------------ 1111ms Dec 16 22:41:53 volumio volumio[1201]: info: sendMpdCommand status took 1106 milliseconds Dec 16 22:41:53 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 1079 milliseconds Dec 16 22:41:53 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 1080 milliseconds Dec 16 22:41:53 volumio volumio[1201]: verbose: ControllerMpd::parseState Dec 16 22:41:53 volumio volumio[1201]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 22:41:53 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:53 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:53 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:53 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:53 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1066,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:53 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:53 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:53 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:53 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:53 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1066,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:53 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:53 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:53 volumio volumio[1201]: info: ------------------------------ 1495ms Dec 16 22:41:53 volumio volumio[1201]: info: ------------------------------ 1462ms Dec 16 22:41:53 volumio volumio[1201]: info: sendMpdCommand playlistinfo took 306 milliseconds Dec 16 22:41:53 volumio volumio[1201]: verbose: ControllerMpd::parseTrackInfo Dec 16 22:41:53 volumio volumio[1201]: info: ControllerMpd::pushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::servicePushState Dec 16 22:41:53 volumio volumio[1201]: info: CorePlayQueue::getTrack 0 Dec 16 22:41:53 volumio volumio[1201]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1158,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":" - ","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} Dec 16 22:41:53 volumio volumio[1201]: verbose: CURRENT POSITION 0 Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::syncState stateService play Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::syncState currentStatus play Dec 16 22:41:53 volumio volumio[1201]: info: Received an update from plugin. extracting info from payload Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreStateMachine::pushState Dec 16 22:41:53 volumio volumio[1201]: info: CoreCommandRouter::volumioPushState Dec 16 22:41:53 volumio volumio[1201]: info: ------------------------------ 1727ms Dec 16 22:41:53 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:53 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:53 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:53 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:53 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:41:53 volumio volumio[1201]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 16 22:42:00 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 22:42:00 volumio volumio[1201]: info: Received Get System Info Dec 16 22:42:00 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 22:42:00 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 22:42:00 volumio volumio[1201]: info: Discovery: Getting this device information Dec 16 22:42:00 volumio volumio[1201]: info: CoreCommandRouter::volumioGetState Dec 16 22:42:00 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 22:42:05 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 22:42:05 volumio volumio[1201]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 16 22:42:21 volumio volumio[1201]: info: Starting Uninstall of plugin user_interface - touch_display Dec 16 22:42:21 volumio volumio[1201]: info: Uninstalling plugin touch_display Dec 16 22:42:21 volumio sudo[2498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 16 22:42:21 volumio sudo[2498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:42:21 volumio sudo[2498]: pam_unix(sudo:session): session closed for user root Dec 16 22:42:21 volumio volumio[1201]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 16 22:42:21 volumio sudo[2500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 16 22:42:21 volumio sudo[2503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 16 22:42:21 volumio sudo[2503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:42:21 volumio volumio[1201]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:42:21 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:42:21 volumio sudo[2505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 16 22:42:21 volumio volumio[1201]: TypeError: Cannot read properties of undefined (reading 'split') Dec 16 22:42:21 volumio volumio[1201]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 16 22:42:21 volumio volumio[1201]: at ChildProcess.exithandler (node:child_process:413:7) Dec 16 22:42:21 volumio volumio[1201]: at ChildProcess.emit (node:events:514:28) Dec 16 22:42:21 volumio volumio[1201]: at maybeClose (node:internal/child_process:1105:16) Dec 16 22:42:21 volumio volumio[1201]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 16 22:42:21 volumio sudo[2505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 22:42:21 volumio volumio[1201]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 22:42:21 volumio systemd[1]: Reloading. Dec 16 22:42:23 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 22:42:23 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 16 22:42:23 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 16 22:42:23 volumio sudo[2503]: pam_unix(sudo:session): session closed for user root Dec 16 22:42:23 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 16 22:42:23 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Dec 16 22:42:23 volumio sudo[2505]: pam_unix(sudo:session): session closed for user root Dec 16 22:42:28 volumio sudo[2556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-16 22:41' Dec 16 22:42:28 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"