-- 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"