-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-07-18 20:15:31 CEST. -- Jul 18 20:14:00 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:00 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:00 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:00 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:01 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:01 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:01 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:01 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:02 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:02 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:02 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:02 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:03 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:03 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:03 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:03 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:04 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:04 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:04 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:04 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:05 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:05 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:05 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:05 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:06 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:06 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:06 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:06 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:07 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:07 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:07 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:07 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:08 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:08 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:08 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:08 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:09 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:09 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:09 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:09 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:09 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jul 18 20:14:09 volumio volumio[973]: info: Calm Radio group browse calmradio://2/56 Jul 18 20:14:09 volumio volumio[973]: info: Getting Calm Radio channels data Jul 18 20:14:10 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:10 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:10 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:10 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:11 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:11 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:11 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:11 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:12 volumio volumio[973]: info: Calm Radio list channels for group 56 Jul 18 20:14:12 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:12 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:12 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:12 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:12 volumio volumio[973]: info: Preload queue cleared Jul 18 20:14:13 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:13 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:13 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:13 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:14 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:14 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:14 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:14 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:15 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:15 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:15 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:15 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:16 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:16 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:16 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:16 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:17 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:17 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:17 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:17 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:18 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:18 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:18 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:18 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:19 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:19 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:19 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:19 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:20 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:20 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:20 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:20 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:21 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:21 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:21 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:21 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:22 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:22 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:22 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:22 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:23 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:23 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:23 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:23 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:24 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:24 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:24 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:24 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:25 volumio volumio[973]: info: Preload queue cleared Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::ClearQueue Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::stPlaybackTimer Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::serviceStop Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::serviceStop Jul 18 20:14:25 volumio volumio[973]: info: [1721326465348] ControllerWebradio::stop Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::addQueueItems Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::addQueueItems Jul 18 20:14:25 volumio volumio[973]: info: Preload queue cleared Jul 18 20:14:25 volumio volumio[973]: info: Adding Item to queue: calmradio://56/926 Jul 18 20:14:25 volumio volumio[973]: info: Exploding uri calmradio://56/926 in service calmradio Jul 18 20:14:25 volumio volumio[973]: info: Calm Radio explodeUri for Cat 56 Chan 926 Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::volumioPlay Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::play index 0 Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::play index undefined Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::startPlaybackTimer Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: [1721326465506] ControllerCalmRadio::clearAddPlayTrack Jul 18 20:14:25 volumio volumio[973]: { Jul 18 20:14:25 volumio volumio[973]: uri: 'calmradio://56/926', Jul 18 20:14:25 volumio volumio[973]: service: 'calmradio', Jul 18 20:14:25 volumio volumio[973]: trackType: 'CalmRadio', Jul 18 20:14:25 volumio volumio[973]: radioType: 'web', Jul 18 20:14:25 volumio volumio[973]: name: 'MY BEST SLEEP EVER', Jul 18 20:14:25 volumio volumio[973]: title: 'MY BEST SLEEP EVER', Jul 18 20:14:25 volumio volumio[973]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/926/66cbc9ccd9112e847a33e23b2ae07225e702d12dde8d9b7438e535de91fa.png', Jul 18 20:14:25 volumio volumio[973]: type: 'webradio', Jul 18 20:14:25 volumio volumio[973]: samplerate: '', Jul 18 20:14:25 volumio volumio[973]: bitdepth: 0, Jul 18 20:14:25 volumio volumio[973]: channels: 0 Jul 18 20:14:25 volumio volumio[973]: } Jul 18 20:14:25 volumio volumio[973]: info: Calm Radio getStreamUrl for Cat 56 Chan 926 Jul 18 20:14:25 volumio volumio[973]: STREAMURL: https://streams.calmradio.com/api/4582/128/stream Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 20:14:25 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand stop took 273 milliseconds Jul 18 20:14:25 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand stop took 11 milliseconds Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:25 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:25 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:25 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand status took 7 milliseconds Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand clear took 7 milliseconds Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand status took 5 milliseconds Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand status took 5 milliseconds Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand status took 4 milliseconds Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/4582/128/stream" Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:25 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 18 20:14:25 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 18 20:14:25 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:25 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:25 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 24ms Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 24ms Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 23ms Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: error: updateQueue error: null Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 39ms Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand playlistinfo took 37 milliseconds Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand addid "https://streams.calmradio.com/api/4582/128/stream" took 37 milliseconds Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 20ms Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 10ms Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 9ms Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:25 volumio volumio[973]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 20:14:25 volumio volumio[973]: verbose: MPD COMMAND [object Object] Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 54ms Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:25 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:25 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 16ms Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 8ms Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 7ms Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 7ms Jul 18 20:14:25 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 20:14:25 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand play Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: Jul 18 20:14:25 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:25 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 5ms Jul 18 20:14:25 volumio volumio[973]: info: sendMpdCommand play took 3 milliseconds Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 4ms Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 3ms Jul 18 20:14:25 volumio volumio[973]: info: ------------------------------ 2ms Jul 18 20:14:26 volumio volumio[973]: 0+0 records in Jul 18 20:14:26 volumio volumio[973]: 0+0 records out Jul 18 20:14:26 volumio volumio[973]: 0 bytes copied, 9.7776e-05 s, 0.0 kB/s Jul 18 20:14:26 volumio volumio[973]: info: camilladsp spawned new process with pid 3435, instance 1, run: true Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 18 20:14:27 volumio volumio[973]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 18 20:14:27 volumio volumio[973]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 21 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 21 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 19 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 18 milliseconds Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 11 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 7 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 5 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 4 milliseconds Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: 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":"MY BEST SLEEP EVER","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus stop Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: 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":"MY BEST SLEEP EVER","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: 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":"MY BEST SLEEP EVER","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: 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":"MY BEST SLEEP EVER","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 59ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 67ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 67ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 66ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 36 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 35 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 34 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 34 milliseconds Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: 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":"MY BEST SLEEP EVER","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: 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":"MY BEST SLEEP EVER","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: 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":"MY BEST SLEEP EVER","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: 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":"MY BEST SLEEP EVER","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 92ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 87ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 86ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 85ms Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: info: FusionDsp - Effects disabled Jul 18 20:14:27 volumio volumio[973]: info: FusionDsp - Jul 18 20:14:27 volumio volumio[973]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:27 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:27 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:27 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:27 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:27 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 14ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 11 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 10ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 8 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 9ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 7 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 6ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 6 milliseconds Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:27 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:27 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:27 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:27 volumio volumio[973]: info: Jul 18 20:14:27 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 16ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 15 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 10ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 9 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 9ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 7 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 7ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand status took 6 milliseconds Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1164,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"MY BEST SLEEP EVER - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1164,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"MY BEST SLEEP EVER - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1164,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"MY BEST SLEEP EVER - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1164,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"MY BEST SLEEP EVER - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 72ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 67ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 65ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 64ms Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 43 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 42 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 42 milliseconds Jul 18 20:14:27 volumio volumio[973]: info: sendMpdCommand playlistinfo took 42 milliseconds Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1164,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"MY BEST SLEEP EVER - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1164,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"MY BEST SLEEP EVER - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1164,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"MY BEST SLEEP EVER - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:27 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1164,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - Softest Sleep Ever - My Best Sleep Ever","artist":"MY BEST SLEEP EVER - ROYALTY FREE - sampler","album":null,"uri":"https://streams.calmradio.com/api/4582/128/stream","trackType":""} Jul 18 20:14:27 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:27 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 103ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 96ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 95ms Jul 18 20:14:27 volumio volumio[973]: info: ------------------------------ 94ms Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:27 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:27 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:27 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:28 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:28 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:28 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:28 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:29 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:29 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:29 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:29 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:30 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:30 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:30 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:30 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:31 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:31 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:31 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:31 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:32 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:32 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:32 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:32 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:33 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:33 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:33 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:33 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:34 volumio volumio[973]: info: Preload queue cleared Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::ClearQueue Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::stPlaybackTimer Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::serviceStop Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::serviceStop Jul 18 20:14:34 volumio volumio[973]: info: [1721326474395] ControllerCalmRadio::stop Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::addQueueItems Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::addQueueItems Jul 18 20:14:34 volumio volumio[973]: info: Preload queue cleared Jul 18 20:14:34 volumio volumio[973]: info: Adding Item to queue: calmradio://56/688 Jul 18 20:14:34 volumio volumio[973]: info: Exploding uri calmradio://56/688 in service calmradio Jul 18 20:14:34 volumio volumio[973]: info: Calm Radio explodeUri for Cat 56 Chan 688 Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::volumioPlay Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::play index 0 Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::play index undefined Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::startPlaybackTimer Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: [1721326474522] ControllerCalmRadio::clearAddPlayTrack Jul 18 20:14:34 volumio volumio[973]: { Jul 18 20:14:34 volumio volumio[973]: uri: 'calmradio://56/688', Jul 18 20:14:34 volumio volumio[973]: service: 'calmradio', Jul 18 20:14:34 volumio volumio[973]: trackType: 'CalmRadio', Jul 18 20:14:34 volumio volumio[973]: radioType: 'web', Jul 18 20:14:34 volumio volumio[973]: name: 'FLYING DREAMS', Jul 18 20:14:34 volumio volumio[973]: title: 'FLYING DREAMS', Jul 18 20:14:34 volumio volumio[973]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/688/136886d57ea5054e2c6757b9a8e781769c372f651d694705e3547b70c480.png', Jul 18 20:14:34 volumio volumio[973]: type: 'webradio', Jul 18 20:14:34 volumio volumio[973]: samplerate: '', Jul 18 20:14:34 volumio volumio[973]: bitdepth: 0, Jul 18 20:14:34 volumio volumio[973]: channels: 0 Jul 18 20:14:34 volumio volumio[973]: } Jul 18 20:14:34 volumio volumio[973]: info: Calm Radio getStreamUrl for Cat 56 Chan 688 Jul 18 20:14:34 volumio volumio[973]: STREAMURL: https://streams.calmradio.com/api/2436/128/stream Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 20:14:34 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand stop took 262 milliseconds Jul 18 20:14:34 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand stop took 29 milliseconds Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:34 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:34 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:34 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand status took 18 milliseconds Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand clear took 17 milliseconds Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand status took 16 milliseconds Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand status took 5 milliseconds Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand status took 5 milliseconds Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/2436/128/stream" Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:34 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 18 20:14:34 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 18 20:14:34 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:34 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:34 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 73ms Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 72ms Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 62ms Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: error: updateQueue error: null Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 65ms Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand playlistinfo took 62 milliseconds Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand addid "https://streams.calmradio.com/api/2436/128/stream" took 62 milliseconds Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 5ms Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 6ms Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 5ms Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:34 volumio volumio[973]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 20:14:34 volumio volumio[973]: verbose: MPD COMMAND [object Object] Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 86ms Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:34 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:34 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 44ms Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 41ms Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 40ms Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 39ms Jul 18 20:14:34 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 18 20:14:34 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand play Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: Jul 18 20:14:34 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:34 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 6ms Jul 18 20:14:34 volumio volumio[973]: info: sendMpdCommand play took 5 milliseconds Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 4ms Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 3ms Jul 18 20:14:34 volumio volumio[973]: info: ------------------------------ 2ms Jul 18 20:14:35 volumio volumio[973]: info: Jul 18 20:14:35 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:35 volumio volumio[973]: info: Jul 18 20:14:35 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:35 volumio volumio[973]: info: Jul 18 20:14:35 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:35 volumio volumio[973]: info: Jul 18 20:14:35 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 18 20:14:35 volumio volumio[973]: info: Jul 18 20:14:35 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand status took 16 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand status took 16 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand status took 15 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand status took 15 milliseconds Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:35 volumio volumio[973]: info: Jul 18 20:14:35 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:35 volumio volumio[973]: info: Jul 18 20:14:35 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:35 volumio volumio[973]: info: Jul 18 20:14:35 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand status took 6 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand status took 3 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand status took 3 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand status took 2 milliseconds Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:35 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:35 volumio volumio[973]: 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":"FLYING DREAMS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2436/128/stream","trackType":""} Jul 18 20:14:35 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus stop Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:35 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:35 volumio volumio[973]: 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":"FLYING DREAMS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2436/128/stream","trackType":""} Jul 18 20:14:35 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:35 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:35 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:35 volumio volumio[973]: 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":"FLYING DREAMS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2436/128/stream","trackType":""} Jul 18 20:14:35 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:35 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:35 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:35 volumio volumio[973]: 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":"FLYING DREAMS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2436/128/stream","trackType":""} Jul 18 20:14:35 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:35 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: ------------------------------ 37ms Jul 18 20:14:35 volumio volumio[973]: info: ------------------------------ 45ms Jul 18 20:14:35 volumio volumio[973]: info: ------------------------------ 45ms Jul 18 20:14:35 volumio volumio[973]: info: ------------------------------ 45ms Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 18 20:14:35 volumio volumio[973]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 18 20:14:35 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:35 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:35 volumio volumio[973]: 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":"FLYING DREAMS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2436/128/stream","trackType":""} Jul 18 20:14:35 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:35 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:35 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:35 volumio volumio[973]: 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":"FLYING DREAMS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2436/128/stream","trackType":""} Jul 18 20:14:35 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:35 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:35 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:35 volumio volumio[973]: 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":"FLYING DREAMS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2436/128/stream","trackType":""} Jul 18 20:14:35 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:35 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:35 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:35 volumio volumio[973]: 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":"FLYING DREAMS","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2436/128/stream","trackType":""} Jul 18 20:14:35 volumio volumio[973]: verbose: CURRENT POSITION 0 Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Jul 18 20:14:35 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:35 volumio volumio[973]: info: ------------------------------ 56ms Jul 18 20:14:35 volumio volumio[973]: info: ------------------------------ 52ms Jul 18 20:14:35 volumio volumio[973]: info: ------------------------------ 52ms Jul 18 20:14:35 volumio volumio[973]: info: ------------------------------ 51ms Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - Effects disabled Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - Effects disabled Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 18 20:14:35 volumio volumio[973]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 18 20:14:35 volumio volumio[973]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jul 18 20:14:35 volumio volumio[973]: 2+28 records in Jul 18 20:14:35 volumio volumio[973]: 2+28 records out Jul 18 20:14:35 volumio volumio[973]: 176384 bytes (176 kB, 172 KiB) copied, 0.000880113 s, 200 MB/s Jul 18 20:14:35 volumio volumio[973]: info: camilladsp spawned new process with pid 3512, instance 1, run: true Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:35 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:35 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:35 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:36 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:36 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:36 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:36 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:37 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:37 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:37 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:37 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:39 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:39 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:39 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:39 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:40 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:40 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:40 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:40 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:41 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:41 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:41 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:41 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:42 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:42 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:42 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:42 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:43 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:43 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:43 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:43 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:43 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 18 20:14:43 volumio volumio[973]: info: Preload queue cleared Jul 18 20:14:44 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:44 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:44 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:44 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:45 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:45 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:45 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:45 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:46 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:46 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:46 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:46 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:47 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:47 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:47 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:47 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:48 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:48 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:48 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:48 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:49 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:49 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:49 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:49 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:49 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 18 20:14:49 volumio volumio[973]: info: Preload queue cleared Jul 18 20:14:50 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:50 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:50 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:50 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:51 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:51 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:51 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:51 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::getQueue Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getQueue Jul 18 20:14:52 volumio volumio[973]: info: Preload queue cleared Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::ClearQueue Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::stPlaybackTimer Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::serviceStop Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::serviceStop Jul 18 20:14:52 volumio volumio[973]: info: [1721326492779] ControllerCalmRadio::stop Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::addQueueItems Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::addQueueItems Jul 18 20:14:52 volumio volumio[973]: info: Preload queue cleared Jul 18 20:14:52 volumio volumio[973]: info: Adding Item to queue: https://live.streams.klassikradio.de/beats-radio/stream/mp3?aggregator=tunein Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioPlay Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::play index 0 Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::play index undefined Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::startPlaybackTimer Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: [1721326492791] ControllerWebradio::clearAddPlayTrack Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 20:14:52 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:52 volumio volumio[973]: info: Jul 18 20:14:52 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:52 volumio volumio[973]: info: sendMpdCommand stop took 16 milliseconds Jul 18 20:14:52 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:52 volumio volumio[973]: info: sendMpdCommand stop took 5 milliseconds Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand clear Jul 18 20:14:52 volumio volumio[973]: info: Jul 18 20:14:52 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:52 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:52 volumio volumio[973]: info: Jul 18 20:14:52 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:52 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:52 volumio volumio[973]: info: Jul 18 20:14:52 volumio volumio[973]: ---------------------------- MPD announces state update: player Jul 18 20:14:52 volumio volumio[973]: info: ControllerMpd::getState Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Jul 18 20:14:52 volumio volumio[973]: info: Jul 18 20:14:52 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:52 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:52 volumio volumio[973]: info: sendMpdCommand status took 9 milliseconds Jul 18 20:14:52 volumio volumio[973]: info: sendMpdCommand clear took 8 milliseconds Jul 18 20:14:52 volumio volumio[973]: info: sendMpdCommand status took 6 milliseconds Jul 18 20:14:52 volumio volumio[973]: info: sendMpdCommand status took 3 milliseconds Jul 18 20:14:52 volumio volumio[973]: info: sendMpdCommand status took 3 milliseconds Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand load "https://live.streams.klassikradio.de/beats-radio/stream/mp3?aggregator=tunein" Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::parseState Jul 18 20:14:52 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 18 20:14:52 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 18 20:14:52 volumio volumio[973]: info: ControllerMpd::pushState Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::servicePushState Jul 18 20:14:52 volumio volumio[973]: info: CoreStateMachine::pushState Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Jul 18 20:14:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:14:52 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 18 20:14:52 volumio volumio[973]: info: ------------------------------ 22ms Jul 18 20:14:52 volumio volumio[973]: info: ------------------------------ 20ms Jul 18 20:14:52 volumio volumio[973]: info: ------------------------------ 19ms Jul 18 20:14:52 volumio volumio[973]: info: Jul 18 20:14:52 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:52 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:52 volumio volumio[973]: info: Jul 18 20:14:52 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:52 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:52 volumio volumio[973]: info: Jul 18 20:14:52 volumio volumio[973]: ---------------------------- MPD announces system playlist update Jul 18 20:14:52 volumio volumio[973]: info: Ignoring MPD Status Update Jul 18 20:14:52 volumio volumio[973]: error: updateQueue error: null Jul 18 20:14:52 volumio volumio[973]: info: ------------------------------ 24ms Jul 18 20:14:52 volumio volumio[973]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 18 20:14:52 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Jul 18 20:14:52 volumio volumio[973]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 18 20:14:52 volumio volumio[973]: info: ------------------------------ 35ms Jul 18 20:14:52 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:52 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:52 volumio volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 18 20:14:53 volumio volumio[973]: 0+0 records in Jul 18 20:14:53 volumio volumio[973]: 0+0 records out Jul 18 20:14:53 volumio volumio[973]: 0 bytes copied, 9.7146e-05 s, 0.0 kB/s Jul 18 20:14:53 volumio volumio[973]: info: camilladsp spawned new process with pid 3563, instance 1, run: true Jul 18 20:15:01 volumio volumio[973]: info: Preload queue cleared Jul 18 20:15:01 volumio volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::ClearQueue Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:01 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue Jul 18 20:15:01 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:15:01 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::addQueueItems Jul 18 20:15:01 volumio volumio[973]: info: CorePlayQueue::addQueueItems Jul 18 20:15:01 volumio volumio[973]: info: Preload queue cleared Jul 18 20:15:01 volumio volumio[973]: info: Adding Item to queue: https://live.streams.klassikradio.de/beats-radio/stream/mp3?aggregator=tunein Jul 18 20:15:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 18 20:15:01 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:15:01 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Jul 18 20:15:01 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Jul 18 20:15:01 volumio volumio[973]: info: CoreCommandRouter::volumioPlay Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::play index 0 Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::play index undefined Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:01 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:15:01 volumio volumio[973]: info: CoreStateMachine::startPlaybackTimer Jul 18 20:15:01 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:15:01 volumio volumio[973]: info: [1721326501028] ControllerWebradio::clearAddPlayTrack Jul 18 20:15:01 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 20:15:05 volumio volumio[973]: info: Preload queue cleared Jul 18 20:15:05 volumio volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 20:15:05 volumio volumio[973]: info: CoreStateMachine::ClearQueue Jul 18 20:15:05 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:15:05 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:05 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue Jul 18 20:15:05 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:15:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:15:05 volumio volumio[973]: info: CoreStateMachine::addQueueItems Jul 18 20:15:05 volumio volumio[973]: info: CorePlayQueue::addQueueItems Jul 18 20:15:05 volumio volumio[973]: info: Preload queue cleared Jul 18 20:15:05 volumio volumio[973]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s268754 Jul 18 20:15:05 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 18 20:15:06 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:15:06 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:15:06 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Jul 18 20:15:06 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Jul 18 20:15:06 volumio volumio[973]: info: CoreCommandRouter::volumioPlay Jul 18 20:15:06 volumio volumio[973]: info: CoreStateMachine::play index 0 Jul 18 20:15:06 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:06 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:15:06 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:06 volumio volumio[973]: info: CoreStateMachine::play index undefined Jul 18 20:15:06 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:06 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:15:06 volumio volumio[973]: info: CoreStateMachine::startPlaybackTimer Jul 18 20:15:06 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:15:06 volumio volumio[973]: info: [1721326506298] ControllerWebradio::clearAddPlayTrack Jul 18 20:15:06 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 20:15:15 volumio volumio[973]: info: Preload queue cleared Jul 18 20:15:15 volumio volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::ClearQueue Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:15 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue Jul 18 20:15:15 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:15:15 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::addQueueItems Jul 18 20:15:15 volumio volumio[973]: info: CorePlayQueue::addQueueItems Jul 18 20:15:15 volumio volumio[973]: info: Preload queue cleared Jul 18 20:15:15 volumio volumio[973]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s309660 Jul 18 20:15:15 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 18 20:15:15 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Jul 18 20:15:15 volumio volumio[973]: info: CorePlayQueue::saveQueue Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Jul 18 20:15:15 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Jul 18 20:15:15 volumio volumio[973]: info: CoreCommandRouter::volumioPlay Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::play index 0 Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::stop Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::play index undefined Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 18 20:15:15 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:15:15 volumio volumio[973]: info: CoreStateMachine::startPlaybackTimer Jul 18 20:15:15 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:15:15 volumio volumio[973]: info: [1721326515635] ControllerWebradio::clearAddPlayTrack Jul 18 20:15:15 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Jul 18 20:15:27 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Jul 18 20:15:27 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Jul 18 20:15:30 volumio volumio[973]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jul 18 20:15:30 volumio volumio[973]: info: PLUGIN onReboot : networkfs Jul 18 20:15:30 volumio volumio[973]: info: PLUGIN onReboot : audiophonicsonoff Jul 18 20:15:30 volumio volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 20:15:30 volumio volumio[973]: TypeError: Cannot read property 'writeSync' of undefined Jul 18 20:15:30 volumio volumio[973]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Jul 18 20:15:30 volumio volumio[973]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jul 18 20:15:30 volumio volumio[973]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jul 18 20:15:30 volumio volumio[973]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 18 20:15:30 volumio volumio[973]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 18 20:15:30 volumio volumio[973]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jul 18 20:15:30 volumio volumio[973]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Jul 18 20:15:30 volumio volumio[973]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:857:33) Jul 18 20:15:30 volumio volumio[973]: at Socket.emit (events.js:315:20) Jul 18 20:15:30 volumio volumio[973]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 18 20:15:30 volumio volumio[973]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 18 20:15:30 volumio volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 20:15:31 volumio sudo[3585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-18 20:14 Jul 18 20:15:31 volumio sudo[3585]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"