Jun 04 08:10:03 volumio volumio[1334]: info: BOOT COMPLETED Jun 04 08:10:08 volumio root[2241]: handle_jack-headphone_event.sh: No supported codec detected, exiting Jun 04 08:10:15 volumio volumio[1334]: info: VolumeController::SetAlsaVolume25 Jun 04 08:10:15 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:15 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:15 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 04 08:10:25 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:26 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:26 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 6ms Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand status took 2 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:26 volumio volumio[1334]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 08:10:26 volumio volumio[1334]: verbose: CURRENT POSITION 0 Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::play index undefined Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2096390256@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1734880737%7D Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 12ms Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand status took 17 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 18ms Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand status took 17 milliseconds Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 31ms Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 31ms Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand stop took 1 milliseconds Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand clear took 0 milliseconds Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-preview-media.sndcdn.com/preview/0/30/12NhWFKIuAAq.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvMTJOaFdGS0l1QUFxLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ5MDE0MTUxfX19XX0_&Signature=ISCt4lVBh9K3H~-5jfnPN~Hk5xwiqr6zgazIoE~SGSaRGINOm9B~asZCM7~3jzsBIb3~c4QEVXnbRQaHFOHwfn5LB6vCWQfLrGjSW1197dpI4eYYRQI7HX21jhRH5bpwkvqJjwhYyyAyWBH11vD6LuCQffHTOE1SlNhsCPuvbXqmFs~NMI6NOIiPuPBRFQRlPBrr30oRNuRfv2XRl57q3TVLWRIJ2dBZ5TmDfkTahGxT6P5aiWCOa2pLis4Xf9rAOIPNpaFTyTecuXuQ8g1A7YVa1J33cxXaBAVkoQUY~55~k24Z7OTdx~xfI2G9NwP28iWaCbnFne~2QXS09YZhBg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand addid "https://cf-preview-media.sndcdn.com/preview/0/30/12NhWFKIuAAq.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvMTJOaFdGS0l1QUFxLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ5MDE0MTUxfX19XX0_&Signature=ISCt4lVBh9K3H~-5jfnPN~Hk5xwiqr6zgazIoE~SGSaRGINOm9B~asZCM7~3jzsBIb3~c4QEVXnbRQaHFOHwfn5LB6vCWQfLrGjSW1197dpI4eYYRQI7HX21jhRH5bpwkvqJjwhYyyAyWBH11vD6LuCQffHTOE1SlNhsCPuvbXqmFs~NMI6NOIiPuPBRFQRlPBrr30oRNuRfv2XRl57q3TVLWRIJ2dBZ5TmDfkTahGxT6P5aiWCOa2pLis4Xf9rAOIPNpaFTyTecuXuQ8g1A7YVa1J33cxXaBAVkoQUY~55~k24Z7OTdx~xfI2G9NwP28iWaCbnFne~2QXS09YZhBg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 2 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:26 volumio volumio[1334]: verbose: MPD COMMAND [object Object] Jun 04 08:10:26 volumio volumio[1334]: verbose: MPD COMMAND [object Object] Jun 04 08:10:26 volumio volumio[1334]: verbose: MPD COMMAND [object Object] Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:26 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand play took 1 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand status took 6 milliseconds Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand status took 7 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand status took 7 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Got Better","artist":"(Preview) Morgan Wallen","album":"I’m The Problem","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/12NhWFKIuAAq.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvMTJOaFdGS0l1QUFxLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ5MDE0MTUxfX19XX0_&Signature=ISCt4lVBh9K3H~-5jfnPN~Hk5xwiqr6zgazIoE~SGSaRGINOm9B~asZCM7~3jzsBIb3~c4QEVXnbRQaHFOHwfn5LB6vCWQfLrGjSW1197dpI4eYYRQI7HX21jhRH5bpwkvqJjwhYyyAyWBH11vD6LuCQffHTOE1SlNhsCPuvbXqmFs~NMI6NOIiPuPBRFQRlPBrr30oRNuRfv2XRl57q3TVLWRIJ2dBZ5TmDfkTahGxT6P5aiWCOa2pLis4Xf9rAOIPNpaFTyTecuXuQ8g1A7YVa1J33cxXaBAVkoQUY~55~k24Z7OTdx~xfI2G9NwP28iWaCbnFne~2QXS09YZhBg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Jun 04 08:10:26 volumio volumio[1334]: verbose: CURRENT POSITION 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 11ms Jun 04 08:10:26 volumio volumio[1334]: info: Jun 04 08:10:26 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand status took 5 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Got Better","artist":"(Preview) Morgan Wallen","album":"I’m The Problem","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/12NhWFKIuAAq.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvMTJOaFdGS0l1QUFxLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ5MDE0MTUxfX19XX0_&Signature=ISCt4lVBh9K3H~-5jfnPN~Hk5xwiqr6zgazIoE~SGSaRGINOm9B~asZCM7~3jzsBIb3~c4QEVXnbRQaHFOHwfn5LB6vCWQfLrGjSW1197dpI4eYYRQI7HX21jhRH5bpwkvqJjwhYyyAyWBH11vD6LuCQffHTOE1SlNhsCPuvbXqmFs~NMI6NOIiPuPBRFQRlPBrr30oRNuRfv2XRl57q3TVLWRIJ2dBZ5TmDfkTahGxT6P5aiWCOa2pLis4Xf9rAOIPNpaFTyTecuXuQ8g1A7YVa1J33cxXaBAVkoQUY~55~k24Z7OTdx~xfI2G9NwP28iWaCbnFne~2QXS09YZhBg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Jun 04 08:10:26 volumio volumio[1334]: verbose: CURRENT POSITION 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Jun 04 08:10:26 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Got Better","artist":"(Preview) Morgan Wallen","album":"I’m The Problem","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/12NhWFKIuAAq.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvMTJOaFdGS0l1QUFxLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ5MDE0MTUxfX19XX0_&Signature=ISCt4lVBh9K3H~-5jfnPN~Hk5xwiqr6zgazIoE~SGSaRGINOm9B~asZCM7~3jzsBIb3~c4QEVXnbRQaHFOHwfn5LB6vCWQfLrGjSW1197dpI4eYYRQI7HX21jhRH5bpwkvqJjwhYyyAyWBH11vD6LuCQffHTOE1SlNhsCPuvbXqmFs~NMI6NOIiPuPBRFQRlPBrr30oRNuRfv2XRl57q3TVLWRIJ2dBZ5TmDfkTahGxT6P5aiWCOa2pLis4Xf9rAOIPNpaFTyTecuXuQ8g1A7YVa1J33cxXaBAVkoQUY~55~k24Z7OTdx~xfI2G9NwP28iWaCbnFne~2QXS09YZhBg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Jun 04 08:10:26 volumio volumio[1334]: verbose: CURRENT POSITION 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Jun 04 08:10:26 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Got Better","artist":"(Preview) Morgan Wallen","album":"I’m The Problem","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/12NhWFKIuAAq.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvMTJOaFdGS0l1QUFxLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ5MDE0MTUxfX19XX0_&Signature=ISCt4lVBh9K3H~-5jfnPN~Hk5xwiqr6zgazIoE~SGSaRGINOm9B~asZCM7~3jzsBIb3~c4QEVXnbRQaHFOHwfn5LB6vCWQfLrGjSW1197dpI4eYYRQI7HX21jhRH5bpwkvqJjwhYyyAyWBH11vD6LuCQffHTOE1SlNhsCPuvbXqmFs~NMI6NOIiPuPBRFQRlPBrr30oRNuRfv2XRl57q3TVLWRIJ2dBZ5TmDfkTahGxT6P5aiWCOa2pLis4Xf9rAOIPNpaFTyTecuXuQ8g1A7YVa1J33cxXaBAVkoQUY~55~k24Z7OTdx~xfI2G9NwP28iWaCbnFne~2QXS09YZhBg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Jun 04 08:10:26 volumio volumio[1334]: verbose: CURRENT POSITION 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Jun 04 08:10:26 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 31ms Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 31ms Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 26ms Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 04 08:10:26 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:26 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Got Better","artist":"(Preview) Morgan Wallen","album":"I’m The Problem","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/12NhWFKIuAAq.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvMTJOaFdGS0l1QUFxLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ5MDE0MTUxfX19XX0_&Signature=ISCt4lVBh9K3H~-5jfnPN~Hk5xwiqr6zgazIoE~SGSaRGINOm9B~asZCM7~3jzsBIb3~c4QEVXnbRQaHFOHwfn5LB6vCWQfLrGjSW1197dpI4eYYRQI7HX21jhRH5bpwkvqJjwhYyyAyWBH11vD6LuCQffHTOE1SlNhsCPuvbXqmFs~NMI6NOIiPuPBRFQRlPBrr30oRNuRfv2XRl57q3TVLWRIJ2dBZ5TmDfkTahGxT6P5aiWCOa2pLis4Xf9rAOIPNpaFTyTecuXuQ8g1A7YVa1J33cxXaBAVkoQUY~55~k24Z7OTdx~xfI2G9NwP28iWaCbnFne~2QXS09YZhBg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Jun 04 08:10:26 volumio volumio[1334]: verbose: CURRENT POSITION 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Jun 04 08:10:26 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:26 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:26 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":866,"duration":30,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Got Better","artist":"(Preview) Morgan Wallen","album":"I’m The Problem","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/12NhWFKIuAAq.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvMTJOaFdGS0l1QUFxLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ5MDE0MTUxfX19XX0_&Signature=ISCt4lVBh9K3H~-5jfnPN~Hk5xwiqr6zgazIoE~SGSaRGINOm9B~asZCM7~3jzsBIb3~c4QEVXnbRQaHFOHwfn5LB6vCWQfLrGjSW1197dpI4eYYRQI7HX21jhRH5bpwkvqJjwhYyyAyWBH11vD6LuCQffHTOE1SlNhsCPuvbXqmFs~NMI6NOIiPuPBRFQRlPBrr30oRNuRfv2XRl57q3TVLWRIJ2dBZ5TmDfkTahGxT6P5aiWCOa2pLis4Xf9rAOIPNpaFTyTecuXuQ8g1A7YVa1J33cxXaBAVkoQUY~55~k24Z7OTdx~xfI2G9NwP28iWaCbnFne~2QXS09YZhBg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Jun 04 08:10:26 volumio volumio[1334]: verbose: CURRENT POSITION 1 Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Jun 04 08:10:26 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:26 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 39ms Jun 04 08:10:26 volumio volumio[1334]: info: ------------------------------ 35ms Jun 04 08:10:30 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 04 08:10:30 volumio dbus-daemon[945]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.27' (uid=0 pid=2363 comm="timedatectl show --property=NTPSynchronized --valu") Jun 04 08:10:30 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 04 08:10:30 volumio dbus-daemon[945]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 04 08:10:30 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 04 08:10:30 volumio setdatetime-helper.sh[2362]: Time is not synchronized. Attempting to sync... Jun 04 08:10:30 volumio sudo[2371]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 04 Jun 2025 05:10:30 GMT#015' Jun 04 08:10:30 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 04 08:10:30 volumio setdatetime-helper.sh[2372]: Wed Jun 4 08:10:30 +03 2025 Jun 04 08:10:30 volumio setdatetime-helper.sh[2362]: Time synchronized successfully. Jun 04 08:10:30 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:30 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 04 08:10:30 volumio systemd-journald[508]: Time jumped backwards, rotating. Jun 04 08:10:30 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 04 08:10:31 volumio volumio[1334]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::volumioGetState Jun 04 08:10:31 volumio volumio[1334]: info: Disable softmixer device for audio device Jun 04 08:10:31 volumio volumio[1334]: info: Output device has changed, restarting MPD Jun 04 08:10:31 volumio volumio[1334]: info: Output device has changed, restarting Shairport Sync Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 08:10:31 volumio sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 08:10:31 volumio sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:10:31 volumio sudo[2381]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:31 volumio volumio[1334]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 08:10:31 volumio volumio[1334]: info: Restoring Previous Volume level: 25 false false Jun 04 08:10:31 volumio volumio[1334]: info: Volume configurations have been set Jun 04 08:10:31 volumio sudo[2385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 08:10:31 volumio sudo[2385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:10:31 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 04 08:10:31 volumio volumio[1334]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 04 08:10:31 volumio volumio[1334]: info: Disabling external Volume Control Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 08:10:31 volumio volumio[1334]: info: Soft Volume ALSA configuration file deleted Jun 04 08:10:31 volumio volumio[1334]: info: Preparing to generate the ALSA configuration file Jun 04 08:10:31 volumio volumio[1334]: info: MPD Permissions set Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 04 08:10:31 volumio volumio[1334]: info: VolumeController:: Volume=49 Mute =false Jun 04 08:10:31 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:31 volumio volumio[1334]: info: Starting Shairport Sync Jun 04 08:10:31 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 04 08:10:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 04 08:10:31 volumio systemd[1]: mpd.service: Consumed 2.323s CPU time. Jun 04 08:10:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 04 08:10:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 04 08:10:31 volumio volumio[1334]: info: Asound.conf file written Jun 04 08:10:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 04 08:10:31 volumio sudo[2410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 08:10:31 volumio sudo[2410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:10:31 volumio sudo[2414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 04 08:10:31 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:10:31 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 04 08:10:31 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 04 08:10:31 volumio volumio[1334]: Found hardware: "HDA-Intel" "Realtek ALC269VC" "HDA:10ec0269,10ec111e,00100203" "0x10ec" "0x10d0" Jun 04 08:10:31 volumio volumio[1334]: Hardware is initialized using a generic method Jun 04 08:10:31 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 04 08:10:31 volumio volumio[1334]: info: Output device has changed, restarting MPD Jun 04 08:10:31 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 04 08:10:31 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 04 08:10:31 volumio systemd[1]: shairport-sync.service: Consumed 1.954s CPU time. Jun 04 08:10:31 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 04 08:10:31 volumio sudo[2410]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:31 volumio sudo[2423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 08:10:31 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:10:31 volumio volumio[1334]: info: Output device has changed, restarting Shairport Sync Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 08:10:31 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:31 volumio sudo[2426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 08:10:31 volumio sudo[2426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:10:31 volumio volumio[1334]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 08:10:31 volumio sudo[2419]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 04 08:10:31 volumio sudo[2419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 04 08:10:31 volumio sudo[2419]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:31 volumio volumio[1334]: info: MPD Permissions set Jun 04 08:10:31 volumio volumio[1334]: info: Shairport-Sync Started Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 04 08:10:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 08:10:31 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 04 08:10:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 04 08:10:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 04 08:10:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 04 08:10:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 04 08:10:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 04 08:10:31 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 04 08:10:31 volumio volumio[1334]: info: Starting Shairport Sync Jun 04 08:10:31 volumio sudo[2459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 08:10:31 volumio sudo[2459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:10:31 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 04 08:10:31 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 04 08:10:31 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 04 08:10:31 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 04 08:10:31 volumio sudo[2459]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:31 volumio volumio[1334]: info: Shairport-Sync Started Jun 04 08:10:31 volumio sudo[2457]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 04 08:10:31 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 04 08:10:31 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:31 volumio mpd[2463]: 2025-06-04T08:10:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 04 08:10:31 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 04 08:10:31 volumio sudo[2426]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:31 volumio sudo[2385]: pam_unix(sudo:session): session closed for user root Jun 04 08:10:31 volumio volumio[1334]: error: MPD error: The expression evaluated to a falsy value: Jun 04 08:10:31 volumio volumio[1334]: assert.ok(self.idling) Jun 04 08:10:31 volumio volumio[1334]: error: The expression evaluated to a falsy value: Jun 04 08:10:31 volumio volumio[1334]: assert.ok(self.idling) Jun 04 08:10:31 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:33 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 04 08:10:33 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:35 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 04 08:10:35 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:35 volumio volumio[1334]: info: VolumeController::SetAlsaVolume25 Jun 04 08:10:35 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:35 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:35 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:39 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jun 04 08:10:39 volumio volumio[1334]: info: [1749013839112] [80s80s] handleBrowseUri curUri: 80s80s Jun 04 08:10:39 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:40 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jun 04 08:10:40 volumio volumio[1334]: info: [1749013840506] [80s80s] handleBrowseUri curUri: 80s80s/eighties Jun 04 08:10:40 volumio volumio[1334]: info: [1749013840506] [80s80s] getRadioContent url: eighties Jun 04 08:10:40 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:42 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::ClearQueue Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::stop Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::serviceStop Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 08:10:42 volumio volumio[1334]: info: ControllerMpd::stop Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::clearPlayQueue Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::saveQueue Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::addQueueItems Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::addQueueItems Jun 04 08:10:42 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:42 volumio volumio[1334]: info: Adding Item to queue: webeighties/1 Jun 04 08:10:42 volumio volumio[1334]: info: Exploding uri webeighties/1 in service 80s80s Jun 04 08:10:42 volumio volumio[1334]: info: [1749013842677] [80s80s] explodeUri: webeighties/1 Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::saveQueue Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::volumioPlay Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::play index 0 Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::stop Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::play index undefined Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:42 volumio volumio[1334]: info: [1749013842682] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/ndw/mp3-192/volumio Jun 04 08:10:42 volumio volumio[1334]: info: [1749013842682] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=137&count=2 Jun 04 08:10:42 volumio volumio[1334]: info: sendMpdCommand stop took 10 milliseconds Jun 04 08:10:42 volumio volumio[1334]: info: [1749013842886] [80s80s] received new event containing 2 songs. Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 08:10:42 volumio volumio[1334]: info: sendMpdCommand stop took 0 milliseconds Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 08:10:42 volumio volumio[1334]: info: Jun 04 08:10:42 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:42 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:42 volumio volumio[1334]: info: sendMpdCommand clear took 0 milliseconds Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 04 08:10:42 volumio volumio[1334]: info: Jun 04 08:10:42 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:42 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:42 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:42 volumio volumio[1334]: info: Jun 04 08:10:42 volumio volumio[1334]: ---------------------------- MPD announces state update: options Jun 04 08:10:42 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:42 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:42 volumio volumio[1334]: info: sendMpdCommand consume 1 took 1 milliseconds Jun 04 08:10:42 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:42 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:42 volumio volumio[1334]: info: [1749013842888] [80s80s] adding url: http://streams.80s80s.de/ndw/mp3-192/volumio Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/ndw/mp3-192/volumio" Jun 04 08:10:42 volumio volumio[1334]: info: Jun 04 08:10:42 volumio volumio[1334]: ---------------------------- MPD announces state update: options Jun 04 08:10:42 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:42 volumio volumio[1334]: info: Jun 04 08:10:42 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:42 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:42 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Jun 04 08:10:42 volumio volumio[1334]: info: sendMpdCommand add "http://streams.80s80s.de/ndw/mp3-192/volumio" took 1 milliseconds Jun 04 08:10:42 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Jun 04 08:10:42 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:42 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:42 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:42 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:42 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:42 volumio volumio[1334]: info: ------------------------------ 9ms Jun 04 08:10:42 volumio volumio[1334]: info: ------------------------------ 12ms Jun 04 08:10:42 volumio volumio[1334]: info: Jun 04 08:10:42 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:42 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:42 volumio volumio[1334]: info: ------------------------------ 13ms Jun 04 08:10:42 volumio volumio[1334]: info: sendMpdCommand play took 10 milliseconds Jun 04 08:10:42 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:42 volumio volumio[1334]: info: [1749013842901] [80s80s] Pushing the next song state: Hubert Kah - Rosemarie Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:42 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:42 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music5/v4/53/5b/65/535b659a-3a83-ea84-2cd8-df4f9c2dd43b/source/600x600bb.jpg","name":"Hubert Kah - Rosemarie","title":"Rosemarie","artist":"80s80s NDW","album":"","streaming":true,"disableUiControls":true,"duration":"209","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 04 08:10:42 volumio volumio[1334]: verbose: CURRENT POSITION 0 Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Jun 04 08:10:42 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Jun 04 08:10:42 volumio volumio[1334]: info: [1749013842902] [80s80s] PlayNextTrack API delay: 30 Jun 04 08:10:42 volumio volumio[1334]: info: [1749013842903] [80s80s] Setting timer to: 209000 milliseconds. Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:42 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand status took 2 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand status took 2 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:44 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:44 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:44 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:44 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:44 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:44 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 4ms Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:44 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:44 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:44 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:44 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:44 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:44 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 4ms Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:44 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:44 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:44 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:44 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:44 volumio volumio[1334]: info: Jun 04 08:10:44 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand status took 3 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand status took 2 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand status took 2 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:44 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:44 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:44 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:44 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:44 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:44 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:44 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:44 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:44 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:44 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:44 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:44 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:44 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:44 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:44 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 6ms Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 5ms Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 5ms Jun 04 08:10:44 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:50 volumio volumio[1334]: info: Jun 04 08:10:50 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:50 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:50 volumio volumio[1334]: info: Jun 04 08:10:50 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:50 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:50 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:50 volumio volumio[1334]: info: Jun 04 08:10:50 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:50 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:50 volumio volumio[1334]: info: Jun 04 08:10:50 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:50 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:50 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:50 volumio volumio[1334]: info: ------------------------------ 0ms Jun 04 08:10:50 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:50 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:50 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:50 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:50 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:50 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:50 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:50 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:50 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:50 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:50 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:50 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:50 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:50 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:50 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:50 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:50 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:50 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:50 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:50 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:50 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:51 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::ClearQueue Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::stop Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::serviceStop Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Jun 04 08:10:51 volumio volumio[1334]: info: ControllerMpd::stop Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::clearPlayQueue Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::saveQueue Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::addQueueItems Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::addQueueItems Jun 04 08:10:51 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:51 volumio volumio[1334]: info: Adding Item to queue: webeighties/0 Jun 04 08:10:51 volumio volumio[1334]: info: Exploding uri webeighties/0 in service 80s80s Jun 04 08:10:51 volumio volumio[1334]: info: [1749013851378] [80s80s] explodeUri: webeighties/0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::saveQueue Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioPlay Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::play index 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::stop Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::play index undefined Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: [1749013851385] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Jun 04 08:10:51 volumio volumio[1334]: info: [1749013851385] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Jun 04 08:10:51 volumio volumio[1334]: info: Jun 04 08:10:51 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand stop took 14 milliseconds Jun 04 08:10:51 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music5/v4/53/5b/65/535b659a-3a83-ea84-2cd8-df4f9c2dd43b/source/600x600bb.jpg","name":"Hubert Kah - Rosemarie","title":"Rosemarie","artist":"80s80s NDW","album":"","streaming":true,"disableUiControls":true,"duration":"209","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 04 08:10:51 volumio volumio[1334]: verbose: CURRENT POSITION 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:51 volumio volumio[1334]: info: No code Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:51 volumio volumio[1334]: info: Jun 04 08:10:51 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:51 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand status took 11 milliseconds Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand status took 5 milliseconds Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:51 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:51 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:51 volumio volumio[1334]: info: ------------------------------ 22ms Jun 04 08:10:51 volumio volumio[1334]: info: ------------------------------ 15ms Jun 04 08:10:51 volumio volumio[1334]: info: [1749013851727] [80s80s] received new event containing 2 songs. Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand stop took 0 milliseconds Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 08:10:51 volumio volumio[1334]: info: Jun 04 08:10:51 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:51 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand clear took 1 milliseconds Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 04 08:10:51 volumio volumio[1334]: info: Jun 04 08:10:51 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:51 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:51 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:51 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:51 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand consume 1 took 0 milliseconds Jun 04 08:10:51 volumio volumio[1334]: info: ------------------------------ 0ms Jun 04 08:10:51 volumio volumio[1334]: info: [1749013851728] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 0 milliseconds Jun 04 08:10:51 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Jun 04 08:10:51 volumio volumio[1334]: info: Jun 04 08:10:51 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:51 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:51 volumio volumio[1334]: info: Jun 04 08:10:51 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:51 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:51 volumio volumio[1334]: info: sendMpdCommand play took 2 milliseconds Jun 04 08:10:51 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:51 volumio volumio[1334]: info: [1749013851731] [80s80s] Pushing the next song state: New Order - True Faith Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/6d/a1/35/6da1358e-8a2b-d35f-39dc-19226f55a251/source/600x600bb.jpg","name":"New Order - True Faith","title":"True Faith","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"228","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 04 08:10:51 volumio volumio[1334]: verbose: CURRENT POSITION 0 Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Jun 04 08:10:51 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Jun 04 08:10:51 volumio volumio[1334]: info: [1749013851731] [80s80s] PlayNextTrack API delay: 30 Jun 04 08:10:51 volumio volumio[1334]: info: [1749013851732] [80s80s] Setting timer to: 228000 milliseconds. Jun 04 08:10:51 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:51 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:53 volumio volumio[1334]: info: Jun 04 08:10:53 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:53 volumio volumio[1334]: info: Jun 04 08:10:53 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:53 volumio volumio[1334]: info: Jun 04 08:10:53 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:53 volumio volumio[1334]: info: Jun 04 08:10:53 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand status took 2 milliseconds Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:53 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:53 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:53 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:53 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand status took 2 milliseconds Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:53 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:53 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:53 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:53 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:53 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:53 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:53 volumio volumio[1334]: info: ------------------------------ 4ms Jun 04 08:10:53 volumio volumio[1334]: info: ------------------------------ 4ms Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:53 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:53 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:53 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:53 volumio volumio[1334]: info: ------------------------------ 4ms Jun 04 08:10:53 volumio volumio[1334]: info: Jun 04 08:10:53 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:53 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:53 volumio volumio[1334]: info: Jun 04 08:10:53 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:53 volumio volumio[1334]: info: Jun 04 08:10:53 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:53 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:53 volumio volumio[1334]: info: Jun 04 08:10:53 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:53 volumio volumio[1334]: info: ------------------------------ 0ms Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:53 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:53 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:53 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:53 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:53 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:53 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:53 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:53 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:53 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:53 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:53 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:53 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:55 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::ClearQueue Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::stop Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::serviceStop Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Jun 04 08:10:55 volumio volumio[1334]: info: ControllerMpd::stop Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::clearPlayQueue Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::saveQueue Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::addQueueItems Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::addQueueItems Jun 04 08:10:55 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:10:55 volumio volumio[1334]: info: Adding Item to queue: webeighties/3 Jun 04 08:10:55 volumio volumio[1334]: info: Exploding uri webeighties/3 in service 80s80s Jun 04 08:10:55 volumio volumio[1334]: info: [1749013855141] [80s80s] explodeUri: webeighties/3 Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::saveQueue Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioPlay Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::play index 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::stop Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::play index undefined Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: [1749013855145] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/party/mp3-192/volumio Jun 04 08:10:55 volumio volumio[1334]: info: [1749013855145] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=252&count=2 Jun 04 08:10:55 volumio volumio[1334]: info: Jun 04 08:10:55 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand stop took 9 milliseconds Jun 04 08:10:55 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/6d/a1/35/6da1358e-8a2b-d35f-39dc-19226f55a251/source/600x600bb.jpg","name":"New Order - True Faith","title":"True Faith","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"228","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 04 08:10:55 volumio volumio[1334]: verbose: CURRENT POSITION 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:55 volumio volumio[1334]: info: No code Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:55 volumio volumio[1334]: info: Jun 04 08:10:55 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:55 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand status took 18 milliseconds Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand status took 4 milliseconds Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:55 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:55 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::pushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:55 volumio volumio[1334]: info: ------------------------------ 28ms Jun 04 08:10:55 volumio volumio[1334]: info: ------------------------------ 15ms Jun 04 08:10:55 volumio volumio[1334]: info: [1749013855304] [80s80s] received new event containing 2 songs. Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand stop took 1 milliseconds Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 08:10:55 volumio volumio[1334]: info: Jun 04 08:10:55 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:55 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand clear took 0 milliseconds Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 04 08:10:55 volumio volumio[1334]: error: updateQueue error: null Jun 04 08:10:55 volumio volumio[1334]: info: ------------------------------ 0ms Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand consume 1 took 0 milliseconds Jun 04 08:10:55 volumio volumio[1334]: info: [1749013855305] [80s80s] adding url: http://streams.80s80s.de/party/mp3-192/volumio Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" Jun 04 08:10:55 volumio volumio[1334]: info: Jun 04 08:10:55 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:55 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:55 volumio volumio[1334]: info: Jun 04 08:10:55 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:55 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand add "http://streams.80s80s.de/party/mp3-192/volumio" took 1 milliseconds Jun 04 08:10:55 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:55 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Jun 04 08:10:55 volumio volumio[1334]: info: Jun 04 08:10:55 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:55 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:55 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:55 volumio volumio[1334]: info: sendMpdCommand play took 1 milliseconds Jun 04 08:10:55 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:55 volumio volumio[1334]: info: [1749013855308] [80s80s] Pushing the next song state: New Order - Blue Monday Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music124/v4/0d/f1/5c/0df15c40-b8cf-f10c-0dbe-982f3af6c358/source/600x600bb.jpg","name":"New Order - Blue Monday","title":"Blue Monday","artist":"80s80s Party","album":"","streaming":true,"disableUiControls":true,"duration":"233","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 04 08:10:55 volumio volumio[1334]: verbose: CURRENT POSITION 0 Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Jun 04 08:10:55 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Jun 04 08:10:55 volumio volumio[1334]: info: [1749013855308] [80s80s] PlayNextTrack API delay: 30 Jun 04 08:10:55 volumio volumio[1334]: info: [1749013855309] [80s80s] Setting timer to: 233000 milliseconds. Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:55 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:56 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:56 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:56 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:56 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:56 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:56 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:56 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:56 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:56 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:56 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:56 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:56 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 2ms Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:56 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:56 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:56 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:56 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:56 volumio volumio[1334]: info: Jun 04 08:10:56 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand status took 2 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:56 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:56 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:56 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:56 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:56 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:56 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:56 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:56 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:56 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 4ms Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:56 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 04 08:10:56 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:56 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:56 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:56 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:56 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:56 volumio volumio[1334]: info: ------------------------------ 3ms Jun 04 08:10:58 volumio volumio[1334]: info: Jun 04 08:10:58 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:58 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:58 volumio volumio[1334]: info: Jun 04 08:10:58 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:58 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:58 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:58 volumio volumio[1334]: info: Jun 04 08:10:58 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Jun 04 08:10:58 volumio volumio[1334]: info: Ignoring MPD Status Update Jun 04 08:10:58 volumio volumio[1334]: info: Jun 04 08:10:58 volumio volumio[1334]: ---------------------------- MPD announces state update: player Jun 04 08:10:58 volumio volumio[1334]: info: ControllerMpd::getState Jun 04 08:10:58 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Jun 04 08:10:58 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:58 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Jun 04 08:10:58 volumio volumio[1334]: info: ------------------------------ 0ms Jun 04 08:10:58 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Jun 04 08:10:58 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:58 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:58 volumio volumio[1334]: verbose: ControllerMpd::parseState Jun 04 08:10:58 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 08:10:58 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:58 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 04 08:10:58 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:58 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Jun 04 08:10:58 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:58 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:58 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:58 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:58 volumio volumio[1334]: info: ControllerMpd::pushState Jun 04 08:10:58 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Jun 04 08:10:58 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:10:58 volumio volumio[1334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 04 08:10:58 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:58 volumio volumio[1334]: info: ------------------------------ 1ms Jun 04 08:10:59 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 04 08:11:11 volumio volumio[1334]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 08:11:11 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 04 08:11:11 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 04 08:11:11 volumio sudo[2665]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 04 08:11:11 volumio sudo[2665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:11 volumio sudo[2665]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:11 volumio sudo[2670]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 04 08:11:11 volumio sudo[2670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:11 volumio sudo[2670]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:11 volumio sudo[2678]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 04 08:11:11 volumio sudo[2678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:11 volumio sudo[2679]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 04 08:11:11 volumio sudo[2679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:11 volumio sudo[2678]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:11 volumio sudo[2679]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:11 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 04 08:11:11 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 04 08:11:11 volumio sudo[2693]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 08:11:11 volumio sudo[2693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:11 volumio sudo[2690]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 08:11:11 volumio sudo[2690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:11 volumio sudo[2693]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:11 volumio sudo[2695]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 04 08:11:11 volumio sudo[2695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:11 volumio sudo[2690]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:15 volumio sudo[2695]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:32 volumio sudo[2794]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 08:11:32 volumio sudo[2794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:32 volumio sudo[2796]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 08:11:32 volumio sudo[2796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:32 volumio sudo[2794]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:32 volumio sudo[2796]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:32 volumio volumio[1334]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.99 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 04 08:11:32 volumio sudo[2805]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 08:11:32 volumio sudo[2805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:32 volumio sudo[2807]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 08:11:32 volumio sudo[2807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 04 08:11:32 volumio sudo[2805]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:32 volumio sudo[2807]: pam_unix(sudo:session): session closed for user root Jun 04 08:11:32 volumio volumio[1334]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.99 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 04 08:11:32 volumio volumio[1334]: info: CoreCommandRouter::volumioGetState Jun 04 08:11:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:11:33 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 08:11:33 volumio volumio[1334]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 08:11:33 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 08:11:33 volumio volumio[1334]: info: Listing playlists Jun 04 08:11:33 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 04 08:11:33 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 04 08:11:33 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 04 08:11:33 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 04 08:11:35 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 08:11:35 volumio volumio[1334]: info: Received Get System Info Jun 04 08:11:35 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 08:11:35 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 08:11:35 volumio volumio[1334]: info: Discovery: Getting this device information Jun 04 08:11:35 volumio volumio[1334]: info: CoreCommandRouter::volumioGetState Jun 04 08:11:35 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:11:35 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 08:11:36 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 08:11:36 volumio volumio[1334]: info: Received Get System Info Jun 04 08:11:36 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 08:11:36 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 08:11:36 volumio volumio[1334]: info: Discovery: Getting this device information Jun 04 08:11:36 volumio volumio[1334]: info: CoreCommandRouter::volumioGetState Jun 04 08:11:36 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Jun 04 08:11:36 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 08:11:38 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 04 08:11:38 volumio volumio[1334]: info: CoreCommandRouter::volumioGetQueue Jun 04 08:11:38 volumio volumio[1334]: info: CoreStateMachine::getQueue Jun 04 08:11:38 volumio volumio[1334]: info: CorePlayQueue::getQueue Jun 04 08:11:38 volumio volumio[1334]: info: Preload queue cleared Jun 04 08:11:41 volumio volumio[1334]: Searching plugin music_service/webradio Jun 04 08:11:41 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 04 08:11:41 volumio volumio[1334]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 08:11:41 volumio volumio[1334]: XMLStructuredError: Premature end of data in tag br line 189 (Line: 199, Column: 1) Jun 04 08:11:41 volumio volumio[1334]: at new XMLStructuredError (/volumio/node_modules/libxmljs/dist/lib/types.js:23:28) Jun 04 08:11:41 volumio volumio[1334]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:190:23 Jun 04 08:11:41 volumio volumio[1334]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jun 04 08:11:41 volumio volumio[1334]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Jun 04 08:11:41 volumio volumio[1334]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Jun 04 08:11:41 volumio volumio[1334]: domain: 1, Jun 04 08:11:41 volumio volumio[1334]: code: 77, Jun 04 08:11:41 volumio volumio[1334]: level: 3, Jun 04 08:11:41 volumio volumio[1334]: column: 1, Jun 04 08:11:41 volumio volumio[1334]: file: '', Jun 04 08:11:41 volumio volumio[1334]: line: 199, Jun 04 08:11:41 volumio volumio[1334]: str1: 'br', Jun 04 08:11:41 volumio volumio[1334]: str2: undefined, Jun 04 08:11:41 volumio volumio[1334]: str3: undefined, Jun 04 08:11:41 volumio volumio[1334]: int1: 189 Jun 04 08:11:41 volumio volumio[1334]: } Jun 04 08:11:41 volumio volumio[1334]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 08:11:41 volumio sudo[2862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-04 08:10' Jun 04 08:11:41 volumio sudo[2862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a42e8353c03f8616e137cf760ea618e88c395461" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Jun 2 16:46:30 UTC 2025" VOLUMIO_VERSION="4.010" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c04e8f20034852facba163ba7f26248a"