-- Logs begin at Thu 2019-02-14 08:11:58 -02, end at Sun 2025-06-22 04:23:35 -02. -- Jun 22 04:22:15 volumio volumio[1205]: info: CoreCommandRouter::Close All Modals sent Jun 22 04:22:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jun 22 04:22:18 volumio volumio[1205]: info: Calm Radio group browse calmradio://38 Jun 22 04:22:18 volumio volumio[1205]: info: Listing Calm Radio Categories for Group POP / ROCK Jun 22 04:22:18 volumio volumio[1205]: info: Preload queue cleared Jun 22 04:22:26 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jun 22 04:22:26 volumio volumio[1205]: info: Calm Radio group browse calmradio://38/4 Jun 22 04:22:26 volumio volumio[1205]: info: Calm Radio list channels for group 4 Jun 22 04:22:26 volumio volumio[1205]: info: Preload queue cleared Jun 22 04:22:41 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jun 22 04:22:41 volumio volumio[1205]: info: Calm Radio group browse calmradio://4/-1 Jun 22 04:22:41 volumio volumio[1205]: info: Calm Radio list channels for group -1 Jun 22 04:22:44 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jun 22 04:22:44 volumio volumio[1205]: info: Calm Radio group browse calmradio://4/-1 Jun 22 04:22:44 volumio volumio[1205]: info: Calm Radio list channels for group -1 Jun 22 04:22:46 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jun 22 04:22:46 volumio volumio[1205]: info: Calm Radio group browse calmradio://4/-1 Jun 22 04:22:46 volumio volumio[1205]: info: Calm Radio list channels for group -1 Jun 22 04:22:47 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jun 22 04:22:47 volumio volumio[1205]: info: Calm Radio group browse calmradio://4/-1 Jun 22 04:22:47 volumio volumio[1205]: info: Calm Radio list channels for group -1 Jun 22 04:22:49 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jun 22 04:22:49 volumio volumio[1205]: info: Calm Radio group browse calmradio://4/-1 Jun 22 04:22:49 volumio volumio[1205]: info: Calm Radio list channels for group -1 Jun 22 04:22:50 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jun 22 04:22:50 volumio volumio[1205]: info: Calm Radio group browse calmradio://4/-1 Jun 22 04:22:50 volumio volumio[1205]: info: Calm Radio list channels for group -1 Jun 22 04:22:54 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri Jun 22 04:22:54 volumio volumio[1205]: info: Calm Radio group browse calmradio://38/26 Jun 22 04:22:54 volumio volumio[1205]: info: Calm Radio list channels for group 26 Jun 22 04:22:54 volumio volumio[1205]: info: Preload queue cleared Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::volumioStop Jun 22 04:23:14 volumio volumio[1205]: info: CoreStateMachine::stop Jun 22 04:23:14 volumio volumio[1205]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 04:23:14 volumio volumio[1205]: info: CoreStateMachine::stPlaybackTimer Jun 22 04:23:14 volumio volumio[1205]: info: CoreStateMachine::updateTrackBlock Jun 22 04:23:14 volumio volumio[1205]: info: CorePlayQueue::getTrackBlock Jun 22 04:23:14 volumio volumio[1205]: info: CoreStateMachine::pushState Jun 22 04:23:14 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Jun 22 04:23:14 volumio volumio[1205]: info: CoreStateMachine::serviceStop Jun 22 04:23:14 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::serviceStop Jun 22 04:23:14 volumio volumio[1205]: info: [1750573394615] ControllerCalmRadio::stop Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand stop Jun 22 04:23:14 volumio volumio[1205]: info: Jun 22 04:23:14 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:14 volumio volumio[1205]: info: sendMpdCommand stop took 18 milliseconds Jun 22 04:23:14 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:14 volumio volumio[1205]: info: Jun 22 04:23:14 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:14 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:14 volumio volumio[1205]: info: Jun 22 04:23:14 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:14 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:14 volumio volumio[1205]: info: sendMpdCommand status took 2 milliseconds Jun 22 04:23:14 volumio volumio[1205]: info: sendMpdCommand status took 2 milliseconds Jun 22 04:23:14 volumio volumio[1205]: info: sendMpdCommand status took 1 milliseconds Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:14 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 22 04:23:14 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 22 04:23:14 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:14 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:14 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:14 volumio volumio[1205]: info: CoreStateMachine::pushState Jun 22 04:23:14 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Jun 22 04:23:14 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:14 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jun 22 04:23:14 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:14 volumio volumio[1205]: info: CoreStateMachine::pushState Jun 22 04:23:14 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Jun 22 04:23:14 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:14 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jun 22 04:23:14 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:14 volumio volumio[1205]: info: CoreStateMachine::pushState Jun 22 04:23:14 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:14 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Jun 22 04:23:14 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:14 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Jun 22 04:23:14 volumio volumio[1205]: info: ------------------------------ 19ms Jun 22 04:23:14 volumio volumio[1205]: info: ------------------------------ 17ms Jun 22 04:23:14 volumio volumio[1205]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 22 04:23:16 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jun 22 04:23:16 volumio volumio[1205]: info: [1750573396285] [80s80s] handleBrowseUri curUri: 80s80s Jun 22 04:23:16 volumio volumio[1205]: info: Preload queue cleared Jun 22 04:23:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Jun 22 04:23:18 volumio volumio[1205]: info: [1750573398440] [80s80s] handleBrowseUri curUri: 80s80s/nineties Jun 22 04:23:18 volumio volumio[1205]: info: [1750573398440] [80s80s] getRadioContent url: nineties Jun 22 04:23:18 volumio volumio[1205]: info: Preload queue cleared Jun 22 04:23:21 volumio volumio[1205]: info: Preload queue cleared Jun 22 04:23:21 volumio volumio[1205]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::ClearQueue Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::stop Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::clearPlayQueue Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::saveQueue Jun 22 04:23:21 volumio volumio[1205]: info: CoreCommandRouter::volumioPushQueue Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::addQueueItems Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::addQueueItems Jun 22 04:23:21 volumio volumio[1205]: info: Preload queue cleared Jun 22 04:23:21 volumio volumio[1205]: info: Adding Item to queue: webnineties/2 Jun 22 04:23:21 volumio volumio[1205]: info: Exploding uri webnineties/2 in service 80s80s Jun 22 04:23:21 volumio volumio[1205]: info: [1750573401221] [80s80s] explodeUri: webnineties/2 Jun 22 04:23:21 volumio volumio[1205]: info: CoreCommandRouter::volumioPushQueue Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::saveQueue Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::updateTrackBlock Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::getTrackBlock Jun 22 04:23:21 volumio volumio[1205]: info: CoreCommandRouter::volumioPlay Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::play index 0 Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::stop Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::play index undefined Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::startPlaybackTimer Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:21 volumio volumio[1205]: info: [1750573401225] [80s80s] clearAddPlayTrack url: http://streams.90s90s.de/eurodance/mp3-192/volumio Jun 22 04:23:21 volumio volumio[1205]: info: [1750573401225] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=188&count=2 Jun 22 04:23:21 volumio volumio[1205]: info: [1750573401521] [80s80s] received new event containing 2 songs. Jun 22 04:23:21 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand stop Jun 22 04:23:21 volumio volumio[1205]: info: sendMpdCommand stop took 1 milliseconds Jun 22 04:23:21 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand clear Jun 22 04:23:21 volumio volumio[1205]: info: sendMpdCommand clear took 0 milliseconds Jun 22 04:23:21 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 22 04:23:21 volumio volumio[1205]: info: Jun 22 04:23:21 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:21 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:21 volumio volumio[1205]: info: sendMpdCommand consume 1 took 1 milliseconds Jun 22 04:23:21 volumio volumio[1205]: info: [1750573401524] [80s80s] adding url: http://streams.90s90s.de/eurodance/mp3-192/volumio Jun 22 04:23:21 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" Jun 22 04:23:21 volumio volumio[1205]: info: Jun 22 04:23:21 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:21 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:21 volumio volumio[1205]: info: Jun 22 04:23:21 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:21 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:21 volumio volumio[1205]: error: updateQueue error: null Jun 22 04:23:21 volumio volumio[1205]: info: Jun 22 04:23:21 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:21 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:21 volumio volumio[1205]: info: ------------------------------ 2ms Jun 22 04:23:21 volumio volumio[1205]: info: sendMpdCommand add "http://streams.90s90s.de/eurodance/mp3-192/volumio" took 1 milliseconds Jun 22 04:23:21 volumio volumio[1205]: info: ------------------------------ 1ms Jun 22 04:23:21 volumio volumio[1205]: info: ------------------------------ 1ms Jun 22 04:23:21 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand play Jun 22 04:23:21 volumio volumio[1205]: info: Jun 22 04:23:21 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:21 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:21 volumio volumio[1205]: info: Jun 22 04:23:21 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:21 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:21 volumio volumio[1205]: info: ------------------------------ 5ms Jun 22 04:23:21 volumio volumio[1205]: info: sendMpdCommand play took 4 milliseconds Jun 22 04:23:21 volumio volumio[1205]: info: ------------------------------ 3ms Jun 22 04:23:21 volumio volumio[1205]: info: ------------------------------ 3ms Jun 22 04:23:21 volumio volumio[1205]: info: [1750573401531] [80s80s] Pushing the next song state: DJ Company - Rhythm Of Love Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:21 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:21 volumio volumio[1205]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music/60/41/af/mzi.tojfafgz.jpg/600x600bb.jpg","name":"DJ Company - Rhythm Of Love","title":"Rhythm Of Love","artist":"90s90s Dance","album":"","streaming":true,"disableUiControls":true,"duration":"224","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 22 04:23:21 volumio volumio[1205]: verbose: CURRENT POSITION 0 Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::syncState stateService play Jun 22 04:23:21 volumio volumio[1205]: info: CoreStateMachine::syncState currentStatus stop Jun 22 04:23:21 volumio volumio[1205]: info: [1750573401533] [80s80s] PlayNextTrack API delay: 40 Jun 22 04:23:21 volumio volumio[1205]: info: [1750573401540] [80s80s] Setting timer to: 31467 milliseconds. Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:21 volumio volumio[1205]: info: CorePlayQueue::getTrack 1 Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 16 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 3 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 1 milliseconds Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 22ms Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 21ms Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 21ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 8ms Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 6ms Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 5ms Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 3ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 2ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 1 milliseconds Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 3ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 3 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 2ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 2ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 1 milliseconds Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 7ms Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 6ms Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 1ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 1 milliseconds Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 7ms Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 6ms Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 5ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 2ms Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 2ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 1 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 1ms Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jun 22 04:23:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jun 22 04:23:24 volumio volumio[1205]: info: Jun 22 04:23:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::getState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 1 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 4ms Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 2ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand status took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 5ms Jun 22 04:23:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 22 04:23:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jun 22 04:23:24 volumio volumio[1205]: info: ControllerMpd::pushState Jun 22 04:23:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jun 22 04:23:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jun 22 04:23:24 volumio volumio[1205]: info: ------------------------------ 5ms Jun 22 04:23:35 volumio volumio[1205]: info: CoreCommandRouter::volumioNext Jun 22 04:23:35 volumio volumio[1205]: info: CoreStateMachine::next Jun 22 04:23:35 volumio volumio[1205]: info: CoreStateMachine::stop Jun 22 04:23:35 volumio volumio[1205]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 04:23:35 volumio volumio[1205]: info: CoreStateMachine::stPlaybackTimer Jun 22 04:23:35 volumio volumio[1205]: info: CoreStateMachine::updateTrackBlock Jun 22 04:23:35 volumio volumio[1205]: info: CorePlayQueue::getTrackBlock Jun 22 04:23:35 volumio volumio[1205]: info: CoreStateMachine::pushState Jun 22 04:23:35 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jun 22 04:23:35 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 04:23:35 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Jun 22 04:23:35 volumio volumio[1205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 04:23:35 volumio volumio[1205]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Jun 22 04:23:35 volumio volumio[1205]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Jun 22 04:23:35 volumio volumio[1205]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:183:10) Jun 22 04:23:35 volumio volumio[1205]: at /volumio/app/index.js:326:81 Jun 22 04:23:35 volumio volumio[1205]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Jun 22 04:23:35 volumio volumio[1205]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Jun 22 04:23:35 volumio volumio[1205]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Jun 22 04:23:35 volumio volumio[1205]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:530:22) Jun 22 04:23:35 volumio volumio[1205]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1243:12) Jun 22 04:23:35 volumio volumio[1205]: at CoreStateMachine.next (/volumio/app/statemachine.js:1176:12) Jun 22 04:23:35 volumio volumio[1205]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:103:28) Jun 22 04:23:35 volumio volumio[1205]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:274:26) Jun 22 04:23:35 volumio volumio[1205]: at Socket.emit (events.js:315:20) Jun 22 04:23:35 volumio volumio[1205]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 22 04:23:35 volumio volumio[1205]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jun 22 04:23:35 volumio volumio[1205]: code: 'NumberExpected' Jun 22 04:23:35 volumio volumio[1205]: } Jun 22 04:23:35 volumio volumio[1205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 04:23:35 volumio sudo[9696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-22 04:22 Jun 22 04:23:35 volumio sudo[9696]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"