-- Logs begin at Sat 2025-08-30 20:36:56 +07, end at Sat 2025-08-30 22:19:30 +07. -- Aug 30 22:18:02 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::ClearQueue Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::clearPlayQueue Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::addQueueItems Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::addQueueItems Aug 30 22:18:02 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:02 volumio1 volumio[1199]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99573504 Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::updateTrackBlock Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::getTrackBlock Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPlay Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::play index 0 Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::play index undefined Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:02 volumio1 volumio[1199]: info: [1756567082032] ControllerWebradio::clearAddPlayTrack Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 22:18:02 volumio1 volumio[1199]: info: Aug 30 22:18:02 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:02 volumio1 volumio[1199]: info: sendMpdCommand stop took 4 milliseconds Aug 30 22:18:02 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 22:18:02 volumio1 volumio[1199]: info: Aug 30 22:18:02 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:02 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:02 volumio1 volumio[1199]: info: Aug 30 22:18:02 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:02 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:02 volumio1 volumio[1199]: info: Aug 30 22:18:02 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:02 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:02 volumio1 volumio[1199]: info: sendMpdCommand status took 2 milliseconds Aug 30 22:18:02 volumio1 volumio[1199]: info: sendMpdCommand clear took 2 milliseconds Aug 30 22:18:02 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:18:02 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99573504" Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:02 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:02 volumio1 volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 22:18:02 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:02 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:02 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:02 volumio1 volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 22:18:02 volumio1 volumio[1199]: info: ------------------------------ 10ms Aug 30 22:18:02 volumio1 volumio[1199]: info: ------------------------------ 10ms Aug 30 22:18:02 volumio1 volumio[1199]: info: Aug 30 22:18:02 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:02 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:02 volumio1 volumio[1199]: info: Aug 30 22:18:02 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:02 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:02 volumio1 volumio[1199]: error: updateQueue error: null Aug 30 22:18:02 volumio1 volumio[1199]: info: ------------------------------ 10ms Aug 30 22:18:02 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 30 22:18:02 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:18:02 volumio1 volumio[1199]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 22:18:02 volumio1 volumio[1199]: info: ------------------------------ 13ms Aug 30 22:18:12 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:18:12 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:18:12 volumio1 volumio[1199]: info: Discovery: Getting this device information Aug 30 22:18:12 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetState Aug 30 22:18:12 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:12 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:18:12 volumio1 volumio[1199]: verbose: New Socket.io Connection to 192.168.0.5:3000 from 192.168.0.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 30 22:18:12 volumio1 volumio[1199]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99573504" took 10533 milliseconds Aug 30 22:18:12 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 22:18:12 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand play Aug 30 22:18:12 volumio1 volumio[1199]: info: Aug 30 22:18:12 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:12 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:12 volumio1 volumio[1199]: info: ------------------------------ 10526ms Aug 30 22:18:12 volumio1 volumio[1199]: info: ------------------------------ 10526ms Aug 30 22:18:12 volumio1 volumio[1199]: Upnp client error: Error: This socket has been ended by the other party Aug 30 22:18:12 volumio1 volumio[1199]: Upnp client error: Error: This socket has been ended by the other party Aug 30 22:18:12 volumio1 volumio[1199]: info: Aug 30 22:18:12 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:12 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:12 volumio1 volumio[1199]: info: Aug 30 22:18:12 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:12 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:12 volumio1 volumio[1199]: Upnp client error: Error: This socket has been ended by the other party Aug 30 22:18:12 volumio1 volumio[1199]: Upnp client error: Error: This socket has been ended by the other party Aug 30 22:18:12 volumio1 volumio[1199]: info: sendMpdCommand play took 2 milliseconds Aug 30 22:18:12 volumio1 volumio[1199]: info: ------------------------------ 2ms Aug 30 22:18:12 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:18:16 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::ClearQueue Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::clearPlayQueue Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::addQueueItems Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::addQueueItems Aug 30 22:18:16 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:16 volumio1 volumio[1199]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99607878 Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::updateTrackBlock Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::getTrackBlock Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPlay Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::play index 0 Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::play index undefined Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:16 volumio1 volumio[1199]: info: [1756567096048] ControllerWebradio::clearAddPlayTrack Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 22:18:16 volumio1 volumio[1199]: info: sendMpdCommand stop took 3 milliseconds Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 22:18:16 volumio1 volumio[1199]: info: Aug 30 22:18:16 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:16 volumio1 volumio[1199]: info: Aug 30 22:18:16 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:16 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:16 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:16 volumio1 volumio[1199]: info: sendMpdCommand clear took 1 milliseconds Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99607878" Aug 30 22:18:16 volumio1 volumio[1199]: info: Aug 30 22:18:16 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:16 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:16 volumio1 volumio[1199]: info: Aug 30 22:18:16 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:16 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:16 volumio1 volumio[1199]: error: updateQueue error: null Aug 30 22:18:16 volumio1 volumio[1199]: info: sendMpdCommand status took 2 milliseconds Aug 30 22:18:16 volumio1 volumio[1199]: info: ------------------------------ 2ms Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:16 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:16 volumio1 volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 22:18:16 volumio1 volumio[1199]: info: ------------------------------ 6ms Aug 30 22:18:16 volumio1 volumio[1199]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99607878" took 152 milliseconds Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand play Aug 30 22:18:16 volumio1 volumio[1199]: info: Aug 30 22:18:16 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:16 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:16 volumio1 volumio[1199]: info: sendMpdCommand status took 153 milliseconds Aug 30 22:18:16 volumio1 volumio[1199]: info: sendMpdCommand status took 153 milliseconds Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:16 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:16 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:16 volumio1 volumio[1199]: 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} Aug 30 22:18:16 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService stop Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:16 volumio1 volumio[1199]: info: No code Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:16 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:16 volumio1 volumio[1199]: 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} Aug 30 22:18:16 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService stop Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:16 volumio1 volumio[1199]: info: No code Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:16 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:16 volumio1 volumio[1199]: info: ------------------------------ 171ms Aug 30 22:18:16 volumio1 volumio[1199]: info: ------------------------------ 172ms Aug 30 22:18:16 volumio1 volumio[1199]: info: Aug 30 22:18:16 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:16 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:16 volumio1 volumio[1199]: info: Aug 30 22:18:16 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:16 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:16 volumio1 volumio[1199]: info: sendMpdCommand play took 21 milliseconds Aug 30 22:18:16 volumio1 volumio[1199]: info: ------------------------------ 21ms Aug 30 22:18:16 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:18:16 volumio1 volumio[1199]: info: ------------------------------ 2ms Aug 30 22:18:21 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:18:21 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:18:21 volumio1 volumio[1199]: info: Discovery: Getting this device information Aug 30 22:18:21 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetState Aug 30 22:18:21 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:18:30 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::ClearQueue Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:30 volumio1 volumio[1199]: info: CorePlayQueue::clearPlayQueue Aug 30 22:18:30 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::addQueueItems Aug 30 22:18:30 volumio1 volumio[1199]: info: CorePlayQueue::addQueueItems Aug 30 22:18:30 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:30 volumio1 volumio[1199]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99608412 Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:30 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::updateTrackBlock Aug 30 22:18:30 volumio1 volumio[1199]: info: CorePlayQueue::getTrackBlock Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPlay Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::play index 0 Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::play index undefined Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:30 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:18:30 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:30 volumio1 volumio[1199]: info: [1756567110081] ControllerWebradio::clearAddPlayTrack Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 22:18:30 volumio1 volumio[1199]: info: Aug 30 22:18:30 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:30 volumio1 volumio[1199]: info: sendMpdCommand stop took 1 milliseconds Aug 30 22:18:30 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 22:18:30 volumio1 volumio[1199]: info: Aug 30 22:18:30 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:30 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:30 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:18:30 volumio1 volumio[1199]: info: sendMpdCommand clear took 1 milliseconds Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99608412" Aug 30 22:18:30 volumio1 volumio[1199]: info: Aug 30 22:18:30 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:30 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:30 volumio1 volumio[1199]: info: Aug 30 22:18:30 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:30 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:30 volumio1 volumio[1199]: error: updateQueue error: null Aug 30 22:18:30 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:18:30 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:18:30 volumio1 volumio[1199]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 22:18:30 volumio1 volumio[1199]: info: ------------------------------ 3ms Aug 30 22:18:30 volumio1 volumio[1199]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99608412" took 186 milliseconds Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand play Aug 30 22:18:30 volumio1 volumio[1199]: info: Aug 30 22:18:30 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:30 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:30 volumio1 volumio[1199]: info: sendMpdCommand status took 185 milliseconds Aug 30 22:18:30 volumio1 volumio[1199]: info: sendMpdCommand status took 185 milliseconds Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:30 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:30 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:30 volumio1 volumio[1199]: 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} Aug 30 22:18:30 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService stop Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:30 volumio1 volumio[1199]: info: No code Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:30 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:30 volumio1 volumio[1199]: 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} Aug 30 22:18:30 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService stop Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:30 volumio1 volumio[1199]: info: No code Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:30 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:30 volumio1 volumio[1199]: info: ------------------------------ 204ms Aug 30 22:18:30 volumio1 volumio[1199]: info: ------------------------------ 204ms Aug 30 22:18:30 volumio1 volumio[1199]: info: Aug 30 22:18:30 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:30 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:30 volumio1 volumio[1199]: info: Aug 30 22:18:30 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:30 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:30 volumio1 volumio[1199]: info: sendMpdCommand play took 20 milliseconds Aug 30 22:18:30 volumio1 volumio[1199]: info: ------------------------------ 20ms Aug 30 22:18:30 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:18:30 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:18:36 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::ClearQueue Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:36 volumio1 volumio[1199]: info: CorePlayQueue::clearPlayQueue Aug 30 22:18:36 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::addQueueItems Aug 30 22:18:36 volumio1 volumio[1199]: info: CorePlayQueue::addQueueItems Aug 30 22:18:36 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:36 volumio1 volumio[1199]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99608412 Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:36 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::updateTrackBlock Aug 30 22:18:36 volumio1 volumio[1199]: info: CorePlayQueue::getTrackBlock Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPlay Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::play index 0 Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::play index undefined Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:36 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:36 volumio1 volumio[1199]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:18:36 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:36 volumio1 volumio[1199]: info: [1756567116828] ControllerWebradio::clearAddPlayTrack Aug 30 22:18:36 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 22:18:36 volumio1 volumio[1199]: info: Aug 30 22:18:36 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:36 volumio1 volumio[1199]: info: sendMpdCommand stop took 1 milliseconds Aug 30 22:18:36 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:36 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:36 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 22:18:36 volumio1 volumio[1199]: info: Aug 30 22:18:36 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:36 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:36 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:18:36 volumio1 volumio[1199]: info: sendMpdCommand clear took 1 milliseconds Aug 30 22:18:36 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:36 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:18:36 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99608412" Aug 30 22:18:36 volumio1 volumio[1199]: info: Aug 30 22:18:36 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:36 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:36 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:36 volumio1 volumio[1199]: info: Aug 30 22:18:36 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:36 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:36 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:36 volumio1 volumio[1199]: error: updateQueue error: null Aug 30 22:18:36 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:18:36 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 22:18:36 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:18:36 volumio1 volumio[1199]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 22:18:36 volumio1 volumio[1199]: info: ------------------------------ 3ms Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:18:45 volumio1 volumio[1199]: info: Discovery: Getting this device information Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetState Aug 30 22:18:45 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:18:45 volumio1 volumio[1199]: verbose: New Socket.io Connection to 192.168.0.5:3000 from 192.168.0.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 30 22:18:45 volumio1 volumio[1199]: verbose: New Socket.io Connection to 192.168.0.5 from 192.168.0.4 UA: Mozilla/5.0 (Linux; Android 14; RMX3630 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 30 22:18:45 volumio1 volumio[1199]: verbose: New Socket.io Connection to 192.168.0.5 from 192.168.0.4 UA: Mozilla/5.0 (Linux; Android 14; RMX3630 Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 30 22:18:45 volumio1 volumio[1199]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99608412" took 8583 milliseconds Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 22:18:45 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand play Aug 30 22:18:45 volumio1 volumio[1199]: Upnp client error: Error: This socket has been ended by the other party Aug 30 22:18:45 volumio1 volumio[1199]: Upnp client error: Error: This socket has been ended by the other party Aug 30 22:18:45 volumio1 volumio[1199]: info: Aug 30 22:18:45 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:45 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:45 volumio1 volumio[1199]: info: Aug 30 22:18:45 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:45 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:45 volumio1 volumio[1199]: info: Aug 30 22:18:45 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:45 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:45 volumio1 volumio[1199]: info: sendMpdCommand status took 8586 milliseconds Aug 30 22:18:45 volumio1 volumio[1199]: info: sendMpdCommand status took 8586 milliseconds Aug 30 22:18:45 volumio1 volumio[1199]: info: sendMpdCommand play took 3 milliseconds Aug 30 22:18:45 volumio1 volumio[1199]: info: ------------------------------ 2ms Aug 30 22:18:45 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:18:45 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:45 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:45 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:45 volumio1 volumio[1199]: 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} Aug 30 22:18:45 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService stop Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:45 volumio1 volumio[1199]: info: No code Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:45 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:45 volumio1 volumio[1199]: 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} Aug 30 22:18:45 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService stop Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:45 volumio1 volumio[1199]: info: No code Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:45 volumio1 volumio[1199]: info: ------------------------------ 8602ms Aug 30 22:18:45 volumio1 volumio[1199]: info: ------------------------------ 8601ms Aug 30 22:18:45 volumio1 volumio[1199]: Upnp client error: Error: This socket has been ended by the other party Aug 30 22:18:45 volumio1 volumio[1199]: info: ------------------------------ 15ms Aug 30 22:18:45 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetState Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 22:18:46 volumio1 volumio[1199]: info: Received Get System Info Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:18:46 volumio1 volumio[1199]: info: Discovery: Getting this device information Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetState Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetState Aug 30 22:18:46 volumio1 volumio[1199]: info: Listing playlists Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetQueue Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreStateMachine::getQueue Aug 30 22:18:46 volumio1 volumio[1199]: info: CorePlayQueue::getQueue Aug 30 22:18:46 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 22:18:49 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:18:49 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:18:49 volumio1 volumio[1199]: info: Discovery: Getting this device information Aug 30 22:18:49 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetState Aug 30 22:18:49 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:18:50 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::ClearQueue Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::clearPlayQueue Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::addQueueItems Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::addQueueItems Aug 30 22:18:50 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:18:50 volumio1 volumio[1199]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99593001 Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::updateTrackBlock Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::getTrackBlock Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPlay Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::play index 0 Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::play index undefined Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:50 volumio1 volumio[1199]: info: [1756567130550] ControllerWebradio::clearAddPlayTrack Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 22:18:50 volumio1 volumio[1199]: info: Aug 30 22:18:50 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:50 volumio1 volumio[1199]: info: sendMpdCommand stop took 1 milliseconds Aug 30 22:18:50 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 22:18:50 volumio1 volumio[1199]: info: Aug 30 22:18:50 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:50 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:50 volumio1 volumio[1199]: info: Aug 30 22:18:50 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:18:50 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:18:50 volumio1 volumio[1199]: info: Aug 30 22:18:50 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:50 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:50 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:18:50 volumio1 volumio[1199]: info: sendMpdCommand clear took 1 milliseconds Aug 30 22:18:50 volumio1 volumio[1199]: info: sendMpdCommand status took 0 milliseconds Aug 30 22:18:50 volumio1 volumio[1199]: info: sendMpdCommand status took 0 milliseconds Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99593001" Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:18:50 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:50 volumio1 volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 22:18:50 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:18:50 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:18:50 volumio1 volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 22:18:50 volumio1 volumio[1199]: info: ------------------------------ 10ms Aug 30 22:18:50 volumio1 volumio[1199]: info: ------------------------------ 9ms Aug 30 22:18:50 volumio1 volumio[1199]: info: Aug 30 22:18:50 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:50 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:50 volumio1 volumio[1199]: info: Aug 30 22:18:50 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:50 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:50 volumio1 volumio[1199]: error: updateQueue error: null Aug 30 22:18:50 volumio1 volumio[1199]: info: ------------------------------ 10ms Aug 30 22:18:50 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:18:50 volumio1 volumio[1199]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 22:18:50 volumio1 volumio[1199]: info: ------------------------------ 11ms Aug 30 22:18:50 volumio1 volumio[1199]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99593001" took 154 milliseconds Aug 30 22:18:50 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 22:18:50 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand play Aug 30 22:18:50 volumio1 volumio[1199]: info: Aug 30 22:18:50 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:50 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:50 volumio1 volumio[1199]: info: ------------------------------ 147ms Aug 30 22:18:50 volumio1 volumio[1199]: info: ------------------------------ 147ms Aug 30 22:18:50 volumio1 volumio[1199]: info: Aug 30 22:18:50 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:50 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:50 volumio1 volumio[1199]: info: Aug 30 22:18:50 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:18:50 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:18:50 volumio1 volumio[1199]: info: sendMpdCommand play took 2 milliseconds Aug 30 22:18:50 volumio1 volumio[1199]: info: ------------------------------ 2ms Aug 30 22:18:50 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:19:00 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::ClearQueue Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::clearPlayQueue Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::addQueueItems Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::addQueueItems Aug 30 22:19:00 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:19:00 volumio1 volumio[1199]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99572208 Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::updateTrackBlock Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::getTrackBlock Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPlay Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::play index 0 Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::play index undefined Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:00 volumio1 volumio[1199]: info: [1756567140278] ControllerWebradio::clearAddPlayTrack Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 22:19:00 volumio1 volumio[1199]: info: Aug 30 22:19:00 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:00 volumio1 volumio[1199]: info: sendMpdCommand stop took 0 milliseconds Aug 30 22:19:00 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 22:19:00 volumio1 volumio[1199]: info: Aug 30 22:19:00 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:00 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:00 volumio1 volumio[1199]: info: Aug 30 22:19:00 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:00 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:00 volumio1 volumio[1199]: info: Aug 30 22:19:00 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:00 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:00 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:19:00 volumio1 volumio[1199]: info: sendMpdCommand clear took 1 milliseconds Aug 30 22:19:00 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:19:00 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99572208" Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:00 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:00 volumio1 volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 22:19:00 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:00 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:00 volumio1 volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 22:19:00 volumio1 volumio[1199]: info: ------------------------------ 8ms Aug 30 22:19:00 volumio1 volumio[1199]: info: ------------------------------ 8ms Aug 30 22:19:00 volumio1 volumio[1199]: info: Aug 30 22:19:00 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:00 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:00 volumio1 volumio[1199]: info: Aug 30 22:19:00 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:00 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:00 volumio1 volumio[1199]: error: updateQueue error: null Aug 30 22:19:00 volumio1 volumio[1199]: info: ------------------------------ 8ms Aug 30 22:19:00 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:00 volumio1 volumio[1199]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 22:19:00 volumio1 volumio[1199]: info: ------------------------------ 10ms Aug 30 22:19:00 volumio1 volumio[1199]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99572208" took 160 milliseconds Aug 30 22:19:00 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 22:19:00 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand play Aug 30 22:19:00 volumio1 volumio[1199]: info: Aug 30 22:19:00 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:00 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:00 volumio1 volumio[1199]: info: ------------------------------ 154ms Aug 30 22:19:00 volumio1 volumio[1199]: info: sendMpdCommand play took 1 milliseconds Aug 30 22:19:00 volumio1 volumio[1199]: info: Aug 30 22:19:00 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:00 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:00 volumio1 volumio[1199]: info: Aug 30 22:19:00 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:00 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:00 volumio1 volumio[1199]: info: ------------------------------ 2ms Aug 30 22:19:00 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:19:00 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:19:09 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::ClearQueue Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::clearPlayQueue Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::addQueueItems Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::addQueueItems Aug 30 22:19:09 volumio1 volumio[1199]: info: Preload queue cleared Aug 30 22:19:09 volumio1 volumio[1199]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99597312 Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::saveQueue Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::updateTrackBlock Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::getTrackBlock Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPlay Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::play index 0 Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::stop Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::play index undefined Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:09 volumio1 volumio[1199]: info: [1756567149761] ControllerWebradio::clearAddPlayTrack Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 22:19:09 volumio1 volumio[1199]: info: Aug 30 22:19:09 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:09 volumio1 volumio[1199]: info: sendMpdCommand stop took 1 milliseconds Aug 30 22:19:09 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 22:19:09 volumio1 volumio[1199]: info: Aug 30 22:19:09 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:09 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:09 volumio1 volumio[1199]: info: Aug 30 22:19:09 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:09 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:09 volumio1 volumio[1199]: info: Aug 30 22:19:09 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:09 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:09 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:19:09 volumio1 volumio[1199]: info: sendMpdCommand clear took 1 milliseconds Aug 30 22:19:09 volumio1 volumio[1199]: info: sendMpdCommand status took 0 milliseconds Aug 30 22:19:09 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99597312" Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:09 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:09 volumio1 volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 22:19:09 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:09 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:09 volumio1 volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 22:19:09 volumio1 volumio[1199]: info: ------------------------------ 8ms Aug 30 22:19:09 volumio1 volumio[1199]: info: ------------------------------ 7ms Aug 30 22:19:09 volumio1 volumio[1199]: info: Aug 30 22:19:09 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:09 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:09 volumio1 volumio[1199]: info: Aug 30 22:19:09 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:09 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:09 volumio1 volumio[1199]: error: updateQueue error: null Aug 30 22:19:09 volumio1 volumio[1199]: info: ------------------------------ 8ms Aug 30 22:19:09 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:09 volumio1 volumio[1199]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 22:19:09 volumio1 volumio[1199]: info: ------------------------------ 10ms Aug 30 22:19:09 volumio1 volumio[1199]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99597312" took 156 milliseconds Aug 30 22:19:09 volumio1 volumio[1199]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 22:19:09 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand play Aug 30 22:19:09 volumio1 volumio[1199]: info: Aug 30 22:19:09 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:09 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:09 volumio1 volumio[1199]: info: ------------------------------ 150ms Aug 30 22:19:09 volumio1 volumio[1199]: info: Aug 30 22:19:09 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:09 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:09 volumio1 volumio[1199]: info: Aug 30 22:19:09 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:09 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:09 volumio1 volumio[1199]: info: sendMpdCommand play took 1 milliseconds Aug 30 22:19:09 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:19:09 volumio1 volumio[1199]: info: ------------------------------ 1ms Aug 30 22:19:09 volumio1 volumio[1199]: info: ------------------------------ 0ms Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 75 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 74 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 74 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"(#1 - 27/500) Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus stop Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 77ms Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 2 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"(#1 - 27/500) Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"(#1 - 27/500) Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 91ms Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 91ms Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"(#1 - 27/500) Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"(#1 - 27/500) Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"(#1 - 27/500) Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 35ms Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 33ms Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 33ms Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:10 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:10 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:10 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:10 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 3ms Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 3 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 3ms Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 2 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:10 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 2ms Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 2 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 2ms Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 5ms Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 4 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Calvin Harris - SMOKE THE PAIN AWAY","artist":"Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Calvin Harris - SMOKE THE PAIN AWAY","artist":"Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 20ms Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 20ms Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces system playlist update Aug 30 22:19:10 volumio1 volumio[1199]: info: Ignoring MPD Status Update Aug 30 22:19:10 volumio1 volumio[1199]: info: Aug 30 22:19:10 volumio1 volumio[1199]: ---------------------------- MPD announces state update: player Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::getState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 0ms Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand status took 1 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseState Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Calvin Harris - SMOKE THE PAIN AWAY","artist":"Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Calvin Harris - SMOKE THE PAIN AWAY","artist":"Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Calvin Harris - SMOKE THE PAIN AWAY","artist":"Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 38ms Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 38ms Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 37ms Aug 30 22:19:10 volumio1 volumio[1199]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 30 22:19:10 volumio1 volumio[1199]: verbose: ControllerMpd::parseTrackInfo Aug 30 22:19:10 volumio1 volumio[1199]: info: ControllerMpd::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::servicePushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CorePlayQueue::getTrack 0 Aug 30 22:19:10 volumio1 volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Calvin Harris - SMOKE THE PAIN AWAY","artist":"Radio Bandit","album":null,"uri":"http://45.90.13.128:8000/stream.mp3","trackType":"mp3"} Aug 30 22:19:10 volumio1 volumio[1199]: verbose: CURRENT POSITION 0 Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState stateService play Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::syncState currentStatus play Aug 30 22:19:10 volumio1 volumio[1199]: info: Received an update from plugin. extracting info from payload Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreStateMachine::pushState Aug 30 22:19:10 volumio1 volumio[1199]: info: CoreCommandRouter::volumioPushState Aug 30 22:19:10 volumio1 volumio[1199]: info: ------------------------------ 28ms Aug 30 22:19:18 volumio1 volumio[1199]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Radio%20Bandit/44d38b70-45e1-4f1e-9caa-0893d183fa57.jpg' Aug 30 22:19:20 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:19:20 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:19:20 volumio1 volumio[1199]: info: Discovery: Getting this device information Aug 30 22:19:20 volumio1 volumio[1199]: info: CoreCommandRouter::volumioGetState Aug 30 22:19:20 volumio1 volumio[1199]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:19:30 volumio1 volumio[1199]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 22:19:30 volumio1 volumio[1199]: Error: read ECONNRESET Aug 30 22:19:30 volumio1 volumio[1199]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Aug 30 22:19:30 volumio1 volumio[1199]: errno: -104, Aug 30 22:19:30 volumio1 volumio[1199]: code: 'ECONNRESET', Aug 30 22:19:30 volumio1 volumio[1199]: syscall: 'read' Aug 30 22:19:30 volumio1 volumio[1199]: } Aug 30 22:19:30 volumio1 volumio[1199]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 22:19:30 volumio1 sudo[11836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 22:18 Aug 30 22:19:30 volumio1 sudo[11836]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"