-- Logs begin at Wed 2024-07-24 17:08:29 UTC, end at Thu 2024-07-25 13:42:31 UTC. -- Jul 25 13:41:22 volumiostc volumio[862]: info: Preload queue cleared Jul 25 13:41:22 volumiostc volumio[862]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::ClearQueue Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::stop Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::stPlaybackTimer Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::updateTrackBlock Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::getTrackBlock Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:22 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:22 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::serviceStop Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:22 volumiostc volumio[862]: info: CoreCommandRouter::serviceStop Jul 25 13:41:22 volumiostc volumio[862]: info: [1721914882978] ControllerWebradio::stop Jul 25 13:41:22 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::clearPlayQueue Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::saveQueue Jul 25 13:41:22 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushQueue Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::addQueueItems Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::addQueueItems Jul 25 13:41:22 volumiostc volumio[862]: info: Preload queue cleared Jul 25 13:41:22 volumiostc volumio[862]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s23211 Jul 25 13:41:22 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 25 13:41:22 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushQueue Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::saveQueue Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::updateTrackBlock Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::getTrackBlock Jul 25 13:41:22 volumiostc volumio[862]: info: CoreCommandRouter::volumioPlay Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::play index 0 Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::stop Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::play index undefined Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:22 volumiostc volumio[862]: info: CoreStateMachine::startPlaybackTimer Jul 25 13:41:22 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:22 volumiostc volumio[862]: info: [1721914882981] ControllerWebradio::clearAddPlayTrack Jul 25 13:41:22 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 13:41:22 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:24 volumiostc volumio[862]: info: sendMpdCommand stop took 1097 milliseconds Jul 25 13:41:24 volumiostc volumio[862]: info: Jul 25 13:41:24 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:24 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:24 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:24 volumiostc volumio[862]: info: sendMpdCommand stop took 1094 milliseconds Jul 25 13:41:24 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand clear Jul 25 13:41:24 volumiostc volumio[862]: info: Jul 25 13:41:24 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:24 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:24 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:24 volumiostc volumio[862]: info: Jul 25 13:41:24 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:24 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:24 volumiostc volumio[862]: info: sendMpdCommand status took 1 milliseconds Jul 25 13:41:24 volumiostc volumio[862]: info: sendMpdCommand clear took 1 milliseconds Jul 25 13:41:24 volumiostc volumio[862]: info: sendMpdCommand status took 1 milliseconds Jul 25 13:41:24 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:24 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:24 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw" Jul 25 13:41:24 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:24 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:24 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:24 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:24 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:24 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:24 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:24 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:24 volumiostc volumio[862]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 25 13:41:24 volumiostc volumio[862]: info: ------------------------------ 3ms Jul 25 13:41:24 volumiostc volumio[862]: info: Jul 25 13:41:24 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:24 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:24 volumiostc volumio[862]: error: updateQueue error: null Jul 25 13:41:24 volumiostc volumio[862]: info: ------------------------------ 2ms Jul 25 13:41:24 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 25 13:41:24 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:24 volumiostc volumio[862]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 25 13:41:24 volumiostc volumio[862]: info: ------------------------------ 4ms Jul 25 13:41:24 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:25 volumiostc volumio[862]: error: updateQueue error: null Jul 25 13:41:25 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw" Jul 25 13:41:25 volumiostc volumio[862]: info: ------------------------------ 1055ms Jul 25 13:41:25 volumiostc volumio[862]: info: Jul 25 13:41:25 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:25 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:25 volumiostc volumio[862]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw" took 0 milliseconds Jul 25 13:41:25 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 25 13:41:25 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand play Jul 25 13:41:25 volumiostc volumio[862]: info: Jul 25 13:41:25 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:25 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:25 volumiostc volumio[862]: info: ------------------------------ 1ms Jul 25 13:41:25 volumiostc volumio[862]: info: sendMpdCommand play took 0 milliseconds Jul 25 13:41:25 volumiostc volumio[862]: info: ------------------------------ 0ms Jul 25 13:41:26 volumiostc volumio[862]: info: Jul 25 13:41:26 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:26 volumiostc volumio[862]: info: Jul 25 13:41:26 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:26 volumiostc volumio[862]: info: Jul 25 13:41:26 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand status took 5 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand status took 4 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand status took 0 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:26 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:26 volumiostc volumio[862]: 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":"WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","trackType":""} Jul 25 13:41:26 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus stop Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:26 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:26 volumiostc volumio[862]: 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":"WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","trackType":""} Jul 25 13:41:26 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:26 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: ------------------------------ 8ms Jul 25 13:41:26 volumiostc volumio[862]: info: ------------------------------ 9ms Jul 25 13:41:26 volumiostc volumio[862]: info: Jul 25 13:41:26 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:26 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:26 volumiostc volumio[862]: 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":"WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","trackType":""} Jul 25 13:41:26 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:26 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: ------------------------------ 11ms Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand status took 8 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:26 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:26 volumiostc volumio[862]: 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":"WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","trackType":""} Jul 25 13:41:26 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:26 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: ------------------------------ 12ms Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:26 volumiostc volumio[862]: info: Jul 25 13:41:26 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:26 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:26 volumiostc volumio[862]: info: Jul 25 13:41:26 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:26 volumiostc volumio[862]: info: Jul 25 13:41:26 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:26 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:26 volumiostc volumio[862]: info: Jul 25 13:41:26 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:26 volumiostc volumio[862]: info: ------------------------------ 1ms Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand status took 1 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: info: ------------------------------ 1ms Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand status took 0 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:26 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:26 volumiostc volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":737,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"TEDDY SWIMS - LOSE CONTROL","artist":"101.9 FM The Mix","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","trackType":""} Jul 25 13:41:26 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:26 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: ------------------------------ 4ms Jul 25 13:41:26 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 25 13:41:26 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:26 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:26 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:26 volumiostc volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":794,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"TEDDY SWIMS - LOSE CONTROL","artist":"101.9 FM The Mix","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/WTMXFM.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY4MSwiaXNzIjoidGlzcnYifQ.tUMJFKfehwO9IC8vTkS7_j8yG8dE_weU1nJuj4oeEjw","trackType":""} Jul 25 13:41:26 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:26 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:26 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:26 volumiostc volumio[862]: info: ------------------------------ 6ms Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:26 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:28 volumiostc volumio[862]: info: Preload queue cleared Jul 25 13:41:28 volumiostc volumio[862]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::ClearQueue Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::stop Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::stPlaybackTimer Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::updateTrackBlock Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::getTrackBlock Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:28 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:28 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::serviceStop Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:28 volumiostc volumio[862]: info: CoreCommandRouter::serviceStop Jul 25 13:41:28 volumiostc volumio[862]: info: [1721914888857] ControllerWebradio::stop Jul 25 13:41:28 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::clearPlayQueue Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::saveQueue Jul 25 13:41:28 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushQueue Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::addQueueItems Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::addQueueItems Jul 25 13:41:28 volumiostc volumio[862]: info: Preload queue cleared Jul 25 13:41:28 volumiostc volumio[862]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s28340 Jul 25 13:41:28 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 25 13:41:28 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushQueue Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::saveQueue Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::updateTrackBlock Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::getTrackBlock Jul 25 13:41:28 volumiostc volumio[862]: info: CoreCommandRouter::volumioPlay Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::play index 0 Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::stop Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::play index undefined Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:28 volumiostc volumio[862]: info: CoreStateMachine::startPlaybackTimer Jul 25 13:41:28 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:28 volumiostc volumio[862]: info: [1721914888862] ControllerWebradio::clearAddPlayTrack Jul 25 13:41:28 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 13:41:28 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:28 volumiostc volumio[862]: info: Tunnel connection is inactive, restarting it Jul 25 13:41:28 volumiostc volumio[862]: info: Starting Tunnel 1 Jul 25 13:41:28 volumiostc volumio[862]: info: Starting Tunnel Connection Checker Jul 25 13:41:29 volumiostc sudo[15240]: volumio : unable to resolve host volumiostc Jul 25 13:41:29 volumiostc sudo[15240]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 25 13:41:29 volumiostc sudo[15240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jul 25 13:41:29 volumiostc sudo[15240]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 25 13:41:29 volumiostc autossh[14837]: received signal to exit (15) Jul 25 13:41:29 volumiostc systemd[1]: Stopping MyVolumio SSH Tunnel... Jul 25 13:41:29 volumiostc systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Jul 25 13:41:29 volumiostc systemd[1]: sshtunnel.service: Succeeded. Jul 25 13:41:29 volumiostc systemd[1]: Stopped MyVolumio SSH Tunnel. Jul 25 13:41:29 volumiostc systemd[1]: Started MyVolumio SSH Tunnel. Jul 25 13:41:29 volumiostc sudo[15240]: pam_unix(sudo:session): session closed for user root Jul 25 13:41:29 volumiostc volumio[862]: info: Remote SSH Started Jul 25 13:41:29 volumiostc autossh[15244]: port set to 0, monitoring disabled Jul 25 13:41:29 volumiostc autossh[15244]: starting ssh (count 1) Jul 25 13:41:29 volumiostc autossh[15244]: ssh child pid is 15247 Jul 25 13:41:29 volumiostc volumio[862]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Jul 25 13:41:29 volumiostc volumio[862]: info: CoreCommandRouter::volumioGetState Jul 25 13:41:29 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:29 volumiostc volumiossh-tunnel[15243]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Jul 25 13:41:29 volumiostc volumio[862]: info: sendMpdCommand stop took 1104 milliseconds Jul 25 13:41:29 volumiostc volumio[862]: info: Jul 25 13:41:29 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:29 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:29 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:29 volumiostc volumio[862]: info: sendMpdCommand stop took 1100 milliseconds Jul 25 13:41:29 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand clear Jul 25 13:41:29 volumiostc volumio[862]: info: Jul 25 13:41:29 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:29 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:29 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:29 volumiostc volumio[862]: info: Jul 25 13:41:29 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:29 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:29 volumiostc volumio[862]: info: sendMpdCommand status took 0 milliseconds Jul 25 13:41:29 volumiostc volumio[862]: info: sendMpdCommand clear took 0 milliseconds Jul 25 13:41:29 volumiostc volumio[862]: info: sendMpdCommand status took 0 milliseconds Jul 25 13:41:29 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:29 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:29 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand load "http://playerservices.streamtheworld.com/pls/WDRVFM.pls?tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8" Jul 25 13:41:29 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:29 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:29 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:29 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:29 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:29 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:29 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:29 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:29 volumiostc volumio[862]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 25 13:41:29 volumiostc volumio[862]: info: ------------------------------ 2ms Jul 25 13:41:29 volumiostc volumio[862]: info: Jul 25 13:41:29 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:29 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:29 volumiostc volumio[862]: error: updateQueue error: null Jul 25 13:41:29 volumiostc volumio[862]: info: ------------------------------ 3ms Jul 25 13:41:29 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 25 13:41:29 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:29 volumiostc volumio[862]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 25 13:41:29 volumiostc volumio[862]: info: ------------------------------ 3ms Jul 25 13:41:29 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:30 volumiostc volumio[862]: info: Jul 25 13:41:30 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:30 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:30 volumiostc volumio[862]: info: sendMpdCommand load "http://playerservices.streamtheworld.com/pls/WDRVFM.pls?tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8" took 112 milliseconds Jul 25 13:41:30 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 25 13:41:30 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand play Jul 25 13:41:30 volumiostc volumio[862]: info: ------------------------------ 110ms Jul 25 13:41:30 volumiostc volumio[862]: info: ------------------------------ 1ms Jul 25 13:41:30 volumiostc volumio[862]: info: Jul 25 13:41:30 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:30 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:30 volumiostc volumio[862]: info: sendMpdCommand play took 1 milliseconds Jul 25 13:41:30 volumiostc volumio[862]: info: ------------------------------ 1ms Jul 25 13:41:31 volumiostc volumio[862]: info: Jul 25 13:41:31 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:31 volumiostc volumio[862]: info: Jul 25 13:41:31 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:31 volumiostc volumio[862]: info: Jul 25 13:41:31 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:31 volumiostc volumio[862]: info: sendMpdCommand status took 4 milliseconds Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:31 volumiostc volumio[862]: info: Jul 25 13:41:31 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:31 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:31 volumiostc volumio[862]: info: Jul 25 13:41:31 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:31 volumiostc volumio[862]: info: sendMpdCommand status took 4 milliseconds Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:31 volumiostc volumio[862]: info: Jul 25 13:41:31 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:31 volumiostc volumio[862]: info: sendMpdCommand status took 1 milliseconds Jul 25 13:41:31 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 25 13:41:31 volumiostc volumio[862]: info: ------------------------------ 1ms Jul 25 13:41:31 volumiostc volumio[862]: info: sendMpdCommand status took 1 milliseconds Jul 25 13:41:31 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:31 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:31 volumiostc volumio[862]: 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":"WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","artist":"97.1 FM The Drive","album":null,"uri":"http://18823.live.streamtheworld.com:80/WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","trackType":""} Jul 25 13:41:31 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus stop Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:31 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:31 volumiostc volumio[862]: 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":"96 Kbps","isStreaming":false,"title":"WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","artist":"97.1 FM The Drive","album":null,"uri":"http://18823.live.streamtheworld.com:80/WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","trackType":""} Jul 25 13:41:31 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:31 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:31 volumiostc volumio[862]: info: ------------------------------ 8ms Jul 25 13:41:31 volumiostc volumio[862]: info: ------------------------------ 8ms Jul 25 13:41:31 volumiostc volumio[862]: info: Jul 25 13:41:31 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:31 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:31 volumiostc volumio[862]: info: Jul 25 13:41:31 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:31 volumiostc volumio[862]: info: sendMpdCommand status took 6 milliseconds Jul 25 13:41:31 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 25 13:41:31 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:31 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:31 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:31 volumiostc volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","artist":"97.1 FM The Drive","album":null,"uri":"http://18823.live.streamtheworld.com:80/WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","trackType":""} Jul 25 13:41:31 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:31 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:31 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:31 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:31 volumiostc volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":489,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","artist":"97.1 FM The Drive","album":null,"uri":"http://18823.live.streamtheworld.com:80/WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","trackType":""} Jul 25 13:41:31 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:31 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:31 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:31 volumiostc volumio[862]: info: ------------------------------ 12ms Jul 25 13:41:31 volumiostc volumio[862]: info: ------------------------------ 11ms Jul 25 13:41:31 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:31 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:31 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:31 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:31 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:31 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:32 volumiostc volumio[862]: info: ------------------------------ 13ms Jul 25 13:41:32 volumiostc volumio[862]: info: sendMpdCommand status took 12 milliseconds Jul 25 13:41:32 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:32 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:32 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:32 volumiostc volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":739,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","artist":"97.1 FM The Drive","album":null,"uri":"http://18823.live.streamtheworld.com:80/WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","trackType":""} Jul 25 13:41:32 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:32 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:32 volumiostc volumio[862]: info: ------------------------------ 21ms Jul 25 13:41:32 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:32 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:32 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:32 volumiostc volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","artist":"97.1 FM The Drive","album":null,"uri":"http://18823.live.streamtheworld.com:80/WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","trackType":""} Jul 25 13:41:32 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:32 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:32 volumiostc volumio[862]: info: ------------------------------ 24ms Jul 25 13:41:32 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:32 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:32 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:32 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:32 volumiostc volumio[862]: info: Jul 25 13:41:32 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:32 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:32 volumiostc volumio[862]: info: Jul 25 13:41:32 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:32 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:32 volumiostc volumio[862]: info: Jul 25 13:41:32 volumiostc volumio[862]: ---------------------------- MPD announces system playlist update Jul 25 13:41:32 volumiostc volumio[862]: info: Ignoring MPD Status Update Jul 25 13:41:32 volumiostc volumio[862]: info: Jul 25 13:41:32 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:32 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:32 volumiostc volumio[862]: info: ------------------------------ 2ms Jul 25 13:41:32 volumiostc volumio[862]: info: sendMpdCommand status took 2 milliseconds Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:32 volumiostc volumio[862]: info: ------------------------------ 2ms Jul 25 13:41:32 volumiostc volumio[862]: info: sendMpdCommand status took 2 milliseconds Jul 25 13:41:32 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:32 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:32 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:32 volumiostc volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1318,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"PEARL JAM - ALIVE","artist":"97.1 FM The Drive","album":null,"uri":"http://18823.live.streamtheworld.com:80/WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","trackType":""} Jul 25 13:41:32 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:32 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:32 volumiostc volumio[862]: info: ------------------------------ 5ms Jul 25 13:41:32 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:32 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:32 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:32 volumiostc volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1352,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"PEARL JAM - ALIVE","artist":"97.1 FM The Drive","album":null,"uri":"http://18823.live.streamtheworld.com:80/WDRVFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzIxOTE0Njc3LCJ0ZC1yZWciOmZhbHNlfQ.AVKAP4yr8bfYG63WA_6G7vczgloYhHtVvWZUJquwWvU&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcyMTkxNDY3NywiaXNzIjoidGlzcnYifQ.SLUymTjLKA0fIwf4q9IugFIgCSJMI6fxO4Mu10BGYR8","trackType":""} Jul 25 13:41:32 volumiostc volumio[862]: verbose: CURRENT POSITION 0 Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::syncState stateService play Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::syncState currentStatus play Jul 25 13:41:32 volumiostc volumio[862]: info: Received an update from plugin. extracting info from payload Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:32 volumiostc volumio[862]: info: ------------------------------ 9ms Jul 25 13:41:32 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:32 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:32 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioStop Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::stop Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::stPlaybackTimer Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::updateTrackBlock Jul 25 13:41:32 volumiostc volumio[862]: info: CorePlayQueue::getTrackBlock Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:32 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:32 volumiostc volumio[862]: info: CoreStateMachine::serviceStop Jul 25 13:41:32 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:32 volumiostc volumio[862]: info: CoreCommandRouter::serviceStop Jul 25 13:41:32 volumiostc volumio[862]: info: [1721914892727] ControllerWebradio::stop Jul 25 13:41:32 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand stop Jul 25 13:41:32 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:33 volumiostc volumio[862]: info: Jul 25 13:41:33 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:33 volumiostc volumio[862]: info: sendMpdCommand stop took 1081 milliseconds Jul 25 13:41:33 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:33 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:33 volumiostc volumio[862]: info: Jul 25 13:41:33 volumiostc volumio[862]: ---------------------------- MPD announces state update: player Jul 25 13:41:33 volumiostc volumio[862]: info: ControllerMpd::getState Jul 25 13:41:33 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand status Jul 25 13:41:33 volumiostc volumio[862]: info: sendMpdCommand status took 1 milliseconds Jul 25 13:41:33 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:33 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:33 volumiostc volumio[862]: info: sendMpdCommand status took 0 milliseconds Jul 25 13:41:33 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 25 13:41:33 volumiostc volumio[862]: verbose: ControllerMpd::parseState Jul 25 13:41:33 volumiostc volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 25 13:41:33 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:33 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:33 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:33 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:33 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:33 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 25 13:41:33 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:33 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:33 volumiostc volumio[862]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 25 13:41:33 volumiostc volumio[862]: info: ------------------------------ 3ms Jul 25 13:41:33 volumiostc volumio[862]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 25 13:41:33 volumiostc volumio[862]: verbose: ControllerMpd::parseTrackInfo Jul 25 13:41:33 volumiostc volumio[862]: info: ControllerMpd::pushState Jul 25 13:41:33 volumiostc volumio[862]: info: CoreCommandRouter::servicePushState Jul 25 13:41:33 volumiostc volumio[862]: info: CoreStateMachine::pushState Jul 25 13:41:33 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:33 volumiostc volumio[862]: info: CoreCommandRouter::volumioPushState Jul 25 13:41:33 volumiostc volumio[862]: info: CorePlayQueue::getTrack 0 Jul 25 13:41:33 volumiostc volumio[862]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 25 13:41:33 volumiostc volumio[862]: info: ------------------------------ 4ms Jul 25 13:41:33 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:41:33 volumiostc volumio[862]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 25 13:42:31 volumiostc volumio[862]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Jul 25 13:42:31 volumiostc volumio[862]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 13:42:31 volumiostc volumio[862]: TypeError: Cannot read property 'then' of undefined Jul 25 13:42:31 volumiostc volumio[862]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:470:20) Jul 25 13:42:31 volumiostc volumio[862]: at Socket.emit (events.js:400:28) Jul 25 13:42:31 volumiostc volumio[862]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 25 13:42:31 volumiostc volumio[862]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 25 13:42:31 volumiostc volumio[862]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 25 13:42:31 volumiostc sudo[15293]: volumio : unable to resolve host volumiostc Jul 25 13:42:31 volumiostc sudo[15293]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 25 13:42:31 volumiostc sudo[15293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-25 13:41 Jul 25 13:42:31 volumiostc sudo[15293]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 04:09:51 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="2fe933ffb50e198f3245fc325fd10b17"