-- Logs begin at Wed 2025-03-19 07:34:21 UTC, end at Wed 2025-03-19 07:39:29 UTC. -- Mar 19 07:38:00 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:00 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:03 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:03 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:04 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:04 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:05 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:05 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:06 volumio-atv volumio[982]: info: VolumeController::SetAlsaVolume+ Mar 19 07:38:06 volumio-atv volumio[982]: null Mar 19 07:38:06 volumio-atv volumio[982]: info: VolumeController::Volume 0 Mar 19 07:38:06 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:06 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:06 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 0 Mar 19 07:38:06 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:06 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:06 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:06 volumio-atv volumio[982]: info: VolumeController::SetAlsaVolume+ Mar 19 07:38:06 volumio-atv volumio[982]: info: Pushing Favourites {"service":"upnp_browser","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","favourite":false} Mar 19 07:38:06 volumio-atv volumio[982]: null Mar 19 07:38:06 volumio-atv volumio[982]: info: VolumeController::Volume 9 Mar 19 07:38:06 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:06 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:06 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 0 Mar 19 07:38:06 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:06 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:06 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:06 volumio-atv volumio[982]: info: Pushing Favourites {"service":"upnp_browser","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","favourite":false} Mar 19 07:38:07 volumio-atv volumio[982]: info: VolumeController::SetAlsaVolume+ Mar 19 07:38:07 volumio-atv volumio[982]: null Mar 19 07:38:07 volumio-atv volumio[982]: info: VolumeController::Volume 18 Mar 19 07:38:07 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:07 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:07 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 0 Mar 19 07:38:07 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:07 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:07 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:07 volumio-atv volumio[982]: info: Pushing Favourites {"service":"upnp_browser","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","favourite":false} Mar 19 07:38:08 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:08 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:09 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:09 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPlay Mar 19 07:38:11 volumio-atv volumio[982]: UNSET VOLATILE Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::play index undefined Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:38:11 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 0 Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:38:11 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 0 Mar 19 07:38:11 volumio-atv volumio[982]: info: [1742369891031] ControllerUPNPBrowser::clearAddPlayTrack Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:38:11 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:11 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:38:11 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:11 volumio-atv volumio[982]: info: Mar 19 07:38:11 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:38:11 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:38:11 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand load "http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3" Mar 19 07:38:11 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:11 volumio-atv volumio[982]: info: Mar 19 07:38:11 volumio-atv volumio[982]: info: ------------------------------ 21ms Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand add "http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3" Mar 19 07:38:11 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:11 volumio-atv volumio[982]: info: Mar 19 07:38:11 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:38:11 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:38:11 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand play Mar 19 07:38:11 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:11 volumio-atv volumio[982]: info: ------------------------------ 9ms Mar 19 07:38:11 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:11 volumio-atv volumio[982]: info: Mar 19 07:38:11 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:38:11 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:11 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:38:11 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:11 volumio-atv volumio[982]: info: Mar 19 07:38:11 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:38:11 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:11 volumio-atv volumio[982]: info: Mar 19 07:38:11 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:38:11 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:38:11 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:11 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::parseTrackInfo Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:38:11 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 0 Mar 19 07:38:11 volumio-atv volumio[982]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":279,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"O0$1$8I1337614.mp3","artist":"$$$!?!?..::Hakanfb::..?!?!$$$","album":"!!Fonky La Famille!! Hakanfb","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","trackType":"mp3"} Mar 19 07:38:11 volumio-atv volumio[982]: info: CURRENT POSITION 0 Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::syncState stateService play Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:11 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:11 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:11 volumio-atv volumio[982]: info: ------------------------------ 163ms Mar 19 07:38:11 volumio-atv volumio[982]: info: Pushing Favourites {"service":"mpd","favourite":false} Mar 19 07:38:11 volumio-atv volumio[982]: info: ------------------------------ 122ms Mar 19 07:38:11 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::parseTrackInfo Mar 19 07:38:11 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:38:11 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 0 Mar 19 07:38:11 volumio-atv volumio[982]: info: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":279,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"O0$1$8I1337614.mp3","artist":"$$$!?!?..::Hakanfb::..?!?!$$$","album":"!!Fonky La Famille!! Hakanfb","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","trackType":"mp3"} Mar 19 07:38:11 volumio-atv volumio[982]: info: CURRENT POSITION 0 Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::syncState stateService play Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::syncState currentStatus play Mar 19 07:38:11 volumio-atv volumio[982]: info: Received an update from plaugin. extracting info from payload Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:11 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:11 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:11 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:11 volumio-atv volumio[982]: info: ------------------------------ 269ms Mar 19 07:38:11 volumio-atv volumio[982]: info: Pushing Favourites {"service":"mpd","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","favourite":false} Mar 19 07:38:11 volumio-atv volumio[982]: info: Pushing Favourites {"service":"mpd","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","favourite":false} Mar 19 07:38:13 volumio-atv volumio[982]: info: VolumeController::SetAlsaVolume+ Mar 19 07:38:13 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:13 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:13 volumio-atv volumio[982]: null Mar 19 07:38:13 volumio-atv volumio[982]: info: VolumeController::Volume 28 Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:13 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:13 volumio-atv volumio[982]: info: Pushing Favourites {"service":"mpd","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","favourite":false} Mar 19 07:38:13 volumio-atv volumio[982]: info: VolumeController::SetAlsaVolume+ Mar 19 07:38:13 volumio-atv volumio[982]: null Mar 19 07:38:13 volumio-atv volumio[982]: info: VolumeController::Volume 38 Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:13 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:13 volumio-atv volumio[982]: info: Pushing Favourites {"service":"mpd","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","favourite":false} Mar 19 07:38:13 volumio-atv volumio[982]: info: VolumeController::SetAlsaVolume+ Mar 19 07:38:13 volumio-atv volumio[982]: null Mar 19 07:38:13 volumio-atv volumio[982]: info: VolumeController::Volume 48 Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:13 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:13 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:13 volumio-atv volumio[982]: info: Pushing Favourites {"service":"mpd","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","favourite":false} Mar 19 07:38:13 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:13 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:15 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:15 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:18 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:18 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:19 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:19 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:22 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:22 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:24 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:24 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:25 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:25 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:29 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:29 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreCommandRouter::volumioNext Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::next Mar 19 07:38:29 volumio-atv volumio[982]: info: ControllerMpd::next Mar 19 07:38:29 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand next Mar 19 07:38:29 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:29 volumio-atv volumio[982]: info: Mar 19 07:38:29 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:38:29 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:29 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:38:29 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:38:29 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:29 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:29 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:38:29 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:38:29 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 0 Mar 19 07:38:29 volumio-atv volumio[982]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 19 07:38:29 volumio-atv volumio[982]: info: CURRENT POSITION 0 Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::syncState stateService stop Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::syncState currentStatus play Mar 19 07:38:29 volumio-atv volumio[982]: info: CURRENT POSITION 0 Mar 19 07:38:29 volumio-atv volumio[982]: info: CURRENT POSITION 1 Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::play index undefined Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:29 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:29 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:29 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:38:29 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:38:29 volumio-atv volumio[982]: info: [1742369909927] ControllerWebradio::clearAddPlayTrack Mar 19 07:38:29 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:38:29 volumio-atv volumio[982]: info: ------------------------------ 52ms Mar 19 07:38:29 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:29 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:29 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:29 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:29 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:29 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:38:29 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:29 volumio-atv volumio[982]: info: Mar 19 07:38:29 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:38:29 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:38:29 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:29 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1420778" Mar 19 07:38:29 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:29 volumio-atv volumio[982]: info: Mar 19 07:38:29 volumio-atv volumio[982]: info: ------------------------------ 8ms Mar 19 07:38:29 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1420778","favourite":false} Mar 19 07:38:29 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1420778","favourite":false} Mar 19 07:38:31 volumio-atv volumio[982]: info: Mar 19 07:38:31 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:38:31 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:38:31 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand play Mar 19 07:38:31 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:31 volumio-atv volumio[982]: info: ------------------------------ 10ms Mar 19 07:38:31 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:31 volumio-atv volumio[982]: info: Mar 19 07:38:31 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:38:31 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:31 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:38:31 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:31 volumio-atv volumio[982]: info: Mar 19 07:38:31 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:38:31 volumio-atv volumio[982]: info: Mar 19 07:38:31 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:38:31 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:38:31 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:31 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::parseTrackInfo Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:38:31 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:38:31 volumio-atv volumio[982]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"78.129.202.10:10568","artist":"Radio Caroline Flashback","album":null,"uri":"http://78.129.202.10:10568","trackType":"10:10568"} Mar 19 07:38:31 volumio-atv volumio[982]: info: CURRENT POSITION 1 Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::syncState stateService play Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:31 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:31 volumio-atv volumio[982]: info: ------------------------------ 72ms Mar 19 07:38:31 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:31 volumio-atv volumio[982]: info: ------------------------------ 36ms Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:38:31 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:31 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::parseTrackInfo Mar 19 07:38:31 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:38:31 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:38:31 volumio-atv volumio[982]: info: STATE SERVICE {"status":"play","position":0,"seek":139,"duration":0,"samplerate":"22.05 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"78.129.202.10:10568","artist":"Radio Caroline Flashback","album":null,"uri":"http://78.129.202.10:10568","trackType":"10:10568"} Mar 19 07:38:31 volumio-atv volumio[982]: info: CURRENT POSITION 1 Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::syncState stateService play Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::syncState currentStatus play Mar 19 07:38:31 volumio-atv volumio[982]: info: Received an update from plaugin. extracting info from payload Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:31 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:31 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:31 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:31 volumio-atv volumio[982]: info: ------------------------------ 128ms Mar 19 07:38:31 volumio-atv volumio[982]: info: Pushing Favourites {"service":"mpd","uri":"http://10.10.11.9:9001/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1337614.mp3","favourite":false} Mar 19 07:38:31 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://78.129.202.10:10568","favourite":false} Mar 19 07:38:31 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://78.129.202.10:10568","favourite":false} Mar 19 07:38:32 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:32 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:33 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:33 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:34 volumio-atv volumio[982]: info: Mar 19 07:38:34 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:38:34 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:38:34 volumio-atv volumio[982]: info: Mar 19 07:38:34 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:38:34 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:38:34 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:38:34 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:34 volumio-atv volumio[982]: info: ------------------------------ 13ms Mar 19 07:38:34 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:34 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:38:34 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:38:34 volumio-atv volumio[982]: info: sending command... Mar 19 07:38:34 volumio-atv volumio[982]: info: parsing response... Mar 19 07:38:34 volumio-atv volumio[982]: info: ControllerMpd::parseTrackInfo Mar 19 07:38:34 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:38:34 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:38:34 volumio-atv volumio[982]: info: STATE SERVICE {"status":"play","position":0,"seek":2786,"duration":0,"samplerate":"22.05 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Bob Dylan - The Times They Are A-Changin'","artist":"Radio Caroline Flashback","album":null,"uri":"http://78.129.202.10:10568","trackType":"10:10568"} Mar 19 07:38:34 volumio-atv volumio[982]: info: CURRENT POSITION 1 Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreStateMachine::syncState stateService play Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreStateMachine::syncState currentStatus play Mar 19 07:38:34 volumio-atv volumio[982]: info: Received an update from plaugin. extracting info from payload Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:34 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:38:34 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:38:34 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:38:34 volumio-atv volumio[982]: info: ------------------------------ 87ms Mar 19 07:38:34 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://78.129.202.10:10568","favourite":false} Mar 19 07:38:34 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://78.129.202.10:10568","favourite":false} Mar 19 07:38:35 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:35 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:38 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:38 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:39 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:39 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:42 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:42 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:43 volumio-atv volumio[982]: info: CoreCommandRouter::volumioGetQueue Mar 19 07:38:43 volumio-atv volumio[982]: info: CoreStateMachine::getQueue Mar 19 07:38:43 volumio-atv volumio[982]: info: CorePlayQueue::getQueue Mar 19 07:38:43 volumio-atv volumio[982]: info: No input data Mar 19 07:38:43 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:43 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:43 volumio-atv volumio[982]: info: No input data Mar 19 07:38:43 volumio-atv volumio[982]: info: No input data Mar 19 07:38:43 volumio-atv volumio[982]: info: No input data Mar 19 07:38:43 volumio-atv volumio[982]: info: No input data Mar 19 07:38:43 volumio-atv volumio[982]: info: No input data Mar 19 07:38:43 volumio-atv volumio[982]: info: No input data Mar 19 07:38:43 volumio-atv volumio[982]: info: No input data Mar 19 07:38:43 volumio-atv volumio[982]: info: No input data Mar 19 07:38:44 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:44 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:48 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 19 07:38:48 volumio-atv volumio[982]: info: CURURI: playlists Mar 19 07:38:48 volumio-atv volumio[982]: info: Listing playlists Mar 19 07:38:48 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:48 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:49 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:49 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:52 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:52 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:53 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:53 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:54 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:54 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:58 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:58 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:59 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:38:59 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:02 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:02 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:03 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:03 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:04 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:04 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:04 volumio-atv volumio[982]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 19 07:39:04 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 19 07:39:04 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 19 07:39:04 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 19 07:39:04 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 19 07:39:04 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 19 07:39:04 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 19 07:39:04 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 19 07:39:05 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 07:39:06 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 19 07:39:08 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:08 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:09 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:09 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:09 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 07:39:12 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:12 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:13 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:13 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:13 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreCommandRouter::volumioStop Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreStateMachine::stop Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:39:14 volumio-atv volumio[982]: UNSET VOLATILE Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreStateMachine::stPlaybackTimer Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreStateMachine::updateTrackBlock Mar 19 07:39:14 volumio-atv volumio[982]: info: CorePlayQueue::getTrackBlock Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:39:14 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:39:14 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreStateMachine::serviceStop Mar 19 07:39:14 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreCommandRouter::serviceStop Mar 19 07:39:14 volumio-atv volumio[982]: info: [1742369954459] ControllerWebradio::stop Mar 19 07:39:14 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:39:14 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:14 volumio-atv volumio[982]: info: Mar 19 07:39:14 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:39:14 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:14 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:39:14 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:39:14 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:14 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:14 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:39:14 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:39:14 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:14 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:14 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:14 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:14 volumio-atv volumio[982]: info: ControllerMpd::parseTrackInfo Mar 19 07:39:14 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:39:14 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:39:14 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:39:14 volumio-atv volumio[982]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 19 07:39:14 volumio-atv volumio[982]: info: ------------------------------ 17ms Mar 19 07:39:14 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1420778","favourite":false} Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPlay Mar 19 07:39:15 volumio-atv volumio[982]: UNSET VOLATILE Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreStateMachine::play index undefined Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:39:15 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:39:15 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:39:15 volumio-atv volumio[982]: info: [1742369955209] ControllerWebradio::clearAddPlayTrack Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:39:15 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:15 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:39:15 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:15 volumio-atv volumio[982]: info: Mar 19 07:39:15 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:39:15 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:39:15 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1420778" Mar 19 07:39:15 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:15 volumio-atv volumio[982]: info: Mar 19 07:39:15 volumio-atv volumio[982]: info: ------------------------------ 10ms Mar 19 07:39:15 volumio-atv volumio[982]: info: Mar 19 07:39:15 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:39:15 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:39:15 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand play Mar 19 07:39:15 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:15 volumio-atv volumio[982]: info: ------------------------------ 8ms Mar 19 07:39:15 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:15 volumio-atv volumio[982]: info: Mar 19 07:39:15 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:39:15 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:15 volumio-atv volumio[982]: info: Mar 19 07:39:15 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:39:15 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:39:15 volumio-atv volumio[982]: info: Mar 19 07:39:15 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:39:15 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:15 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:39:15 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:15 volumio-atv volumio[982]: info: ------------------------------ 10ms Mar 19 07:39:15 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:39:15 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:15 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::parseTrackInfo Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:39:15 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:39:15 volumio-atv volumio[982]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"78.129.202.10:10568","artist":"Radio Caroline Flashback","album":null,"uri":"http://78.129.202.10:10568","trackType":"10:10568"} Mar 19 07:39:15 volumio-atv volumio[982]: info: CURRENT POSITION 1 Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreStateMachine::syncState stateService play Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:39:15 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:39:15 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:39:15 volumio-atv volumio[982]: info: ------------------------------ 62ms Mar 19 07:39:15 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:15 volumio-atv volumio[982]: info: ControllerMpd::parseTrackInfo Mar 19 07:39:16 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:39:16 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:39:16 volumio-atv volumio[982]: info: STATE SERVICE {"status":"play","position":0,"seek":139,"duration":0,"samplerate":"22.05 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"78.129.202.10:10568","artist":"Radio Caroline Flashback","album":null,"uri":"http://78.129.202.10:10568","trackType":"10:10568"} Mar 19 07:39:16 volumio-atv volumio[982]: info: CURRENT POSITION 1 Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreStateMachine::syncState stateService play Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreStateMachine::syncState currentStatus play Mar 19 07:39:16 volumio-atv volumio[982]: info: Received an update from plaugin. extracting info from payload Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:39:16 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:39:16 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:39:16 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:39:16 volumio-atv volumio[982]: info: ------------------------------ 119ms Mar 19 07:39:16 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://78.129.202.10:10568","favourite":false} Mar 19 07:39:16 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://78.129.202.10:10568","favourite":false} Mar 19 07:39:16 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://78.129.202.10:10568","favourite":false} Mar 19 07:39:18 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:18 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:18 volumio-atv volumio[982]: info: Mar 19 07:39:18 volumio-atv volumio[982]: ---------------------------- MPD announces system playlist update Mar 19 07:39:18 volumio-atv volumio[982]: info: Ignoring MPD Status Update Mar 19 07:39:18 volumio-atv volumio[982]: info: Mar 19 07:39:18 volumio-atv volumio[982]: ---------------------------- MPD announces state update: player Mar 19 07:39:18 volumio-atv volumio[982]: info: ControllerMpd::getState Mar 19 07:39:18 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand status Mar 19 07:39:18 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:18 volumio-atv volumio[982]: info: ------------------------------ 14ms Mar 19 07:39:18 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:18 volumio-atv volumio[982]: info: ControllerMpd::parseState Mar 19 07:39:18 volumio-atv volumio[982]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:39:18 volumio-atv volumio[982]: info: sending command... Mar 19 07:39:18 volumio-atv volumio[982]: info: parsing response... Mar 19 07:39:18 volumio-atv volumio[982]: info: ControllerMpd::parseTrackInfo Mar 19 07:39:18 volumio-atv volumio[982]: info: ControllerMpd::pushState Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreCommandRouter::servicePushState Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreStateMachine::syncState Mar 19 07:39:18 volumio-atv volumio[982]: info: CorePlayQueue::getTrack 1 Mar 19 07:39:18 volumio-atv volumio[982]: info: STATE SERVICE {"status":"play","position":0,"seek":2368,"duration":0,"samplerate":"22.05 KHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Bob Dylan - The Times They Are A-Changin'","artist":"Radio Caroline Flashback","album":null,"uri":"http://78.129.202.10:10568","trackType":"10:10568"} Mar 19 07:39:18 volumio-atv volumio[982]: info: CURRENT POSITION 1 Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreStateMachine::syncState stateService play Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreStateMachine::syncState currentStatus play Mar 19 07:39:18 volumio-atv volumio[982]: info: Received an update from plaugin. extracting info from payload Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:39:18 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreStateMachine::pushState Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreStateMachine::getState Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreCommandRouter::volumioPushState Mar 19 07:39:18 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:39:18 volumio-atv volumio[982]: info: interfaceApi::pushState Mar 19 07:39:18 volumio-atv volumio[982]: info: ------------------------------ 92ms Mar 19 07:39:18 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://78.129.202.10:10568","favourite":false} Mar 19 07:39:18 volumio-atv volumio[982]: info: Pushing Favourites {"service":"webradio","uri":"http://78.129.202.10:10568","favourite":false} Mar 19 07:39:19 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:19 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:20 volumio-atv volumio[982]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 19 07:39:20 volumio-atv volumio[982]: info: CURURI: artists:// Mar 19 07:39:22 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:22 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:23 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:23 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:24 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:24 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:27 volumio-atv volumio[982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 07:39:27 volumio-atv volumio[982]: SyntaxError: Unexpected end of JSON input Mar 19 07:39:27 volumio-atv volumio[982]: at JSON.parse () Mar 19 07:39:27 volumio-atv volumio[982]: at IncomingMessage. (/volumio/node_modules/album-art/index.js:30:22) Mar 19 07:39:27 volumio-atv volumio[982]: at emitNone (events.js:111:20) Mar 19 07:39:27 volumio-atv volumio[982]: at IncomingMessage.emit (events.js:208:7) Mar 19 07:39:27 volumio-atv volumio[982]: at endReadableNT (_stream_readable.js:1064:12) Mar 19 07:39:27 volumio-atv volumio[982]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Mar 19 07:39:27 volumio-atv volumio[982]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 19 07:39:27 volumio-atv volumio[982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 07:39:27 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:28 volumio-atv avahi-daemon[673]: Received response from host 10.10.11.141 with invalid source port 56252 on interface 'wlan0.0' Mar 19 07:39:29 volumio-atv sudo[3470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-19 07:38 Mar 19 07:39:29 volumio-atv sudo[3470]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="2f09878fca434492a3a1c8d64c992c38a304bdcb" VOLUMIO_FE_VERSION="81c7100e9c7342b16fc9e0ca5908a26771e08c4f" VOLUMIO_BE_VERSION="9ddaa4edade219b4424aaf553d8a153e2d73143a" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jun 15 20:45:03 CEST 2018" VOLUMIO_VERSION="2.411" VOLUMIO_HARDWARE="x86"