-- Logs begin at Fri 2024-05-17 18:26:22 KST, end at Fri 2026-03-27 12:30:29 KST. -- Mar 27 12:29:01 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:29:01 volumio-ap3 volumio[772]: info: sendMpdCommand status took 4 milliseconds Mar 27 12:29:11 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:29:11 volumio-ap3 volumio[772]: info: sendMpdCommand status took 2 milliseconds Mar 27 12:29:12 volumio-ap3 volumio[772]: info: CoreCommandRouter::executeOnPlugin: dark_ascendants , handleBrowseUri Mar 27 12:29:12 volumio-ap3 volumio[772]: info: [DarkAscendants] handleBrowseUri: darkascendants/playlist/9ac5604e-d456-4079-bcaf-8755a57a972c Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preload queue cleared Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/55172078 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/226602767 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/17730224 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/260529795 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/515765 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/26136133 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/122559498 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/3258611 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/14283660 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/162115904 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/182521354 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/15345626 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/34313228 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/51583368 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/77610095 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/84784925 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/74696054 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Preloading song: darkascendants/track/3044005 Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/55172078 in service dark_ascendants Mar 27 12:29:14 volumio-ap3 volumio[772]: info: CoreCommandRouter::executeOnPlugin: dark_ascendants , handleBrowseUri Mar 27 12:29:14 volumio-ap3 volumio[772]: info: [DarkAscendants] handleBrowseUri: darkascendants/playlist/9ac5604e-d456-4079-bcaf-8755a57a972c Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/226602767 in service dark_ascendants Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/17730224 in service dark_ascendants Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/260529795 in service dark_ascendants Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/515765 in service dark_ascendants Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/26136133 in service dark_ascendants Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/122559498 in service dark_ascendants Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/3258611 in service dark_ascendants Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/14283660 in service dark_ascendants Mar 27 12:29:14 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/162115904 in service dark_ascendants Mar 27 12:29:15 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/182521354 in service dark_ascendants Mar 27 12:29:15 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/15345626 in service dark_ascendants Mar 27 12:29:15 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/34313228 in service dark_ascendants Mar 27 12:29:15 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/51583368 in service dark_ascendants Mar 27 12:29:15 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/77610095 in service dark_ascendants Mar 27 12:29:15 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/84784925 in service dark_ascendants Mar 27 12:29:15 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/74696054 in service dark_ascendants Mar 27 12:29:15 volumio-ap3 volumio[772]: info: Exploding uri darkascendants/track/3044005 in service dark_ascendants Mar 27 12:29:19 volumio-ap3 volumio[772]: error: [DarkAscendants] explodeUri track: getTrackMeta 74696054 HTTP 404 Mar 27 12:29:20 volumio-ap3 volumio[772]: info: Preload queue cleared Mar 27 12:29:21 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:29:21 volumio-ap3 volumio[772]: info: sendMpdCommand status took 14 milliseconds Mar 27 12:29:31 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:29:31 volumio-ap3 volumio[772]: info: sendMpdCommand status took 3 milliseconds Mar 27 12:29:33 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioGetState Mar 27 12:29:33 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 4 Mar 27 12:29:41 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:29:41 volumio-ap3 volumio[772]: info: sendMpdCommand status took 3 milliseconds Mar 27 12:29:51 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:29:51 volumio-ap3 volumio[772]: info: sendMpdCommand status took 4 milliseconds Mar 27 12:30:01 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:01 volumio-ap3 volumio[772]: info: sendMpdCommand status took 2 milliseconds Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Preload queue cleared Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::ClearQueue Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::stop Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::stPlaybackTimer Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::updateTrackBlock Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrackBlock Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 4 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::serviceStop Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 4 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreCommandRouter::serviceStop Mar 27 12:30:02 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::clearPlayQueue Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::saveQueue Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushQueue Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::addQueueItems Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::addQueueItems Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Preload queue cleared Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/55172078 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/55172078 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/226602767 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/226602767 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/17730224 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/17730224 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/260529795 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/260529795 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/515765 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/515765 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/26136133 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/26136133 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/122559498 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/122559498 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/3258611 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/3258611 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/14283660 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/14283660 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/162115904 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/162115904 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/182521354 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/182521354 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/15345626 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/15345626 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/34313228 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/34313228 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/51583368 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/51583368 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/77610095 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/77610095 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/84784925 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/84784925 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/74696054 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/74696054 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Adding Item to queue: darkascendants/track/3044005 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Using cached record of: darkascendants/track/3044005 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushQueue Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::saveQueue Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::updateTrackBlock Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrackBlock Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPlay Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::play index 0 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::stop Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::play index undefined Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CoreStateMachine::startPlaybackTimer Mar 27 12:30:02 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: [DarkAscendants] clearAddPlayTrack quality=HI_RES_LOSSLESS useHiResProxy=true raw=true Mar 27 12:30:02 volumio-ap3 volumio[772]: info: [DarkAscendants] clearAddPlayTrack: darkascendants/track/55172078 Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Mar 27 12:30:02 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:02 volumio-ap3 volumio[772]: info: sendMpdCommand stop took 93 milliseconds Mar 27 12:30:02 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:02 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:02 volumio-ap3 volumio[772]: info: [HiResProxy] client disconnected, stream cancelled Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Mar 27 12:30:02 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:02 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:02 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:02 volumio-ap3 volumio[772]: info: Mar 27 12:30:02 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:02 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:02 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:03 volumio-ap3 volumio[772]: info: sendMpdCommand status took 37 milliseconds Mar 27 12:30:03 volumio-ap3 volumio[772]: info: sendMpdCommand status took 32 milliseconds Mar 27 12:30:03 volumio-ap3 volumio[772]: info: sendMpdCommand status took 29 milliseconds Mar 27 12:30:03 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:03 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:03 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:03 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:03 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:03 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:03 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 27 12:30:03 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 27 12:30:03 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 27 12:30:03 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:03 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:03 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:03 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:03 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:03 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:03 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:03 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:03 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:03 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:03 volumio-ap3 volumio[772]: info: ------------------------------ 107ms Mar 27 12:30:03 volumio-ap3 volumio[772]: info: ------------------------------ 98ms Mar 27 12:30:03 volumio-ap3 volumio[772]: info: ------------------------------ 97ms Mar 27 12:30:04 volumio-ap3 volumio[772]: info: [TidalApi] getStreamUrl[HI_RES_LOSSLESS] trackId=55172078 audioQuality=LOSSLESS mimeType=application/vnd.tidal.bts encType=NONE api.bitDepth=16 api.sampleRate=44100 Mar 27 12:30:04 volumio-ap3 volumio[772]: info: [TidalApi] getStreamUrl OK(BTS): quality=LOSSLESS bitDepth=16 sampleRate=44100 bts.bitDepth=undefined bts.sampleRate=undefined Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: STATE SERVICE {"status":"play","service":"dark_ascendants","uri":"darkascendants/track/55172078","name":"In The Air Tonight (2015 Remastered)","title":"In The Air Tonight (2015 Remastered)","artist":"Phil Collins","album":"Face Value (Deluxe Editon)","albumart":"https://resources.tidal.com/images/468effe2/f73c/4e8b/bcb2/4447c8207f50/1280x1280.jpg","duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2,"seek":0} Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: CURRENT POSITION 0 Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState stateService play Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState currentStatus stop Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 12:30:04 volumio-ap3 volumio[772]: info: sendMpdCommand stop took 6 milliseconds Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand clear Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces system playlist update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Ignoring MPD Status Update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: sendMpdCommand clear took 10 milliseconds Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand add "https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3OTYxYTUzODhiMWY0NThhY2ViNTU2ZDhhYjMyYWZlNF82MS5tcDQ/0.flac?token=1774585509~MmExNWY2OGU1YmNjNzEwMmNhZWU3ZDY0M2QzY2ViMTU0N2IyMTk5Nw==" Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces system playlist update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Ignoring MPD Status Update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces system playlist update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Ignoring MPD Status Update Mar 27 12:30:04 volumio-ap3 volumio[772]: error: updateQueue error: null Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces system playlist update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Ignoring MPD Status Update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ------------------------------ 22ms Mar 27 12:30:04 volumio-ap3 volumio[772]: info: sendMpdCommand add "https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3OTYxYTUzODhiMWY0NThhY2ViNTU2ZDhhYjMyYWZlNF82MS5tcDQ/0.flac?token=1774585509~MmExNWY2OGU1YmNjNzEwMmNhZWU3ZDY0M2QzY2ViMTU0N2IyMTk5Nw==" took 13 milliseconds Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ------------------------------ 10ms Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ------------------------------ 9ms Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand play Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces system playlist update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Ignoring MPD Status Update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces system playlist update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Ignoring MPD Status Update Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ------------------------------ 14ms Mar 27 12:30:04 volumio-ap3 volumio[772]: info: sendMpdCommand play took 10 milliseconds Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ------------------------------ 8ms Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ------------------------------ 6ms Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: STATE SERVICE {"status":"play","service":"dark_ascendants","uri":"darkascendants/track/55172078","name":"In The Air Tonight (2015 Remastered)","title":"In The Air Tonight (2015 Remastered)","artist":"Phil Collins","album":"Face Value (Deluxe Editon)","albumart":"https://resources.tidal.com/images/468effe2/f73c/4e8b/bcb2/4447c8207f50/1280x1280.jpg","duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2,"seek":0} Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: CURRENT POSITION 0 Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState stateService play Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState currentStatus play Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Received an update from plugin. extracting info from payload Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:04 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:04 volumio-ap3 volumio[772]: info: sendMpdCommand status took 20 milliseconds Mar 27 12:30:04 volumio-ap3 volumio[772]: info: sendMpdCommand status took 17 milliseconds Mar 27 12:30:04 volumio-ap3 volumio[772]: info: sendMpdCommand status took 14 milliseconds Mar 27 12:30:04 volumio-ap3 volumio[772]: info: sendMpdCommand status took 11 milliseconds Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:04 volumio-ap3 volumio[772]: info: Mar 27 12:30:04 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:04 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:04 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand status took 29 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand status took 26 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 19 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 18 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand status took 13 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: STATE SERVICE {"status":"play","service":"dark_ascendants","uri":"darkascendants/track/55172078","name":"In The Air Tonight (2015 Remastered)","title":"In The Air Tonight (2015 Remastered)","artist":"Phil Collins","album":"Face Value (Deluxe Editon)","albumart":"https://resources.tidal.com/images/468effe2/f73c/4e8b/bcb2/4447c8207f50/1280x1280.jpg","duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2,"seek":0} Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: CURRENT POSITION 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState stateService play Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState currentStatus play Mar 27 12:30:05 volumio-ap3 volumio[772]: info: Received an update from plugin. extracting info from payload Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ------------------------------ 124ms Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ------------------------------ 119ms Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ------------------------------ 115ms Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand status took 88 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 78 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 60 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 55 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: STATE SERVICE {"status":"play","service":"dark_ascendants","uri":"darkascendants/track/55172078","name":"In The Air Tonight (2015 Remastered)","title":"In The Air Tonight (2015 Remastered)","artist":"Phil Collins","album":"Face Value (Deluxe Editon)","albumart":"https://resources.tidal.com/images/468effe2/f73c/4e8b/bcb2/4447c8207f50/1280x1280.jpg","duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2,"seek":0} Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: CURRENT POSITION 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState stateService play Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState currentStatus play Mar 27 12:30:05 volumio-ap3 volumio[772]: info: Received an update from plugin. extracting info from payload Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ------------------------------ 149ms Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ------------------------------ 131ms Mar 27 12:30:05 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 27 12:30:05 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:05 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:05 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:05 volumio-ap3 volumio[772]: info: ------------------------------ 136ms Mar 27 12:30:10 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioGetState Mar 27 12:30:10 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:13 volumio-ap3 volumio[772]: info: Discovery: Disconnected from remote: 192.168.0.148 Mar 27 12:30:14 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:14 volumio-ap3 volumio[772]: info: sendMpdCommand status took 2 milliseconds Mar 27 12:30:18 volumio-ap3 volumio[772]: info: CoreCommandRouter::executeOnPlugin: dark_ascendants , handleBrowseUri Mar 27 12:30:18 volumio-ap3 volumio[772]: info: [DarkAscendants] handleBrowseUri: darkascendants/songs Mar 27 12:30:19 volumio-ap3 volumio[772]: info: Discovery: Remote connection error: 192.168.0.148 Mar 27 12:30:19 volumio-ap3 volumio[772]: info: Discovery: Remote connection error: 192.168.0.148 Mar 27 12:30:19 volumio-ap3 volumio[772]: info: Discovery: Remote connection error: 192.168.0.148 Mar 27 12:30:19 volumio-ap3 volumio[772]: info: Discovery: Remote connection error: 192.168.0.148 Mar 27 12:30:19 volumio-ap3 volumio[772]: info: Discovery: Remote connection timeout: 192.168.0.148 Mar 27 12:30:19 volumio-ap3 volumio[772]: info: Discovery: Remote connection timeout: 192.168.0.148 Mar 27 12:30:20 volumio-ap3 volumio[772]: info: CoreCommandRouter::executeOnPlugin: dark_ascendants , handleBrowseUri Mar 27 12:30:20 volumio-ap3 volumio[772]: info: [DarkAscendants] handleBrowseUri: darkascendants/songs Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPause Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::pause Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::stPlaybackTimer Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::servicePause Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePause Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand status took 4 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand pause 1 Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand pause 1 took 2 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: STATE SERVICE {"status":"pause","service":"dark_ascendants","uri":"darkascendants/track/55172078","name":"In The Air Tonight (2015 Remastered)","title":"In The Air Tonight (2015 Remastered)","artist":"Phil Collins","album":"Face Value (Deluxe Editon)","albumart":"https://resources.tidal.com/images/468effe2/f73c/4e8b/bcb2/4447c8207f50/1280x1280.jpg","duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2,"seek":18000} Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: CURRENT POSITION 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState stateService pause Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState currentStatus pause Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::stPlaybackTimer Mar 27 12:30:23 volumio-ap3 volumio[772]: info: Mar 27 12:30:23 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:23 volumio-ap3 volumio[772]: info: Mar 27 12:30:23 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:23 volumio-ap3 volumio[772]: info: Mar 27 12:30:23 volumio-ap3 volumio[772]: ---------------------------- MPD announces state update: player Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ControllerMpd::getState Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand status Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand status took 13 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand status took 13 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand status took 10 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand status took 9 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseState Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: ControllerMpd::parseTrackInfo Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: STATE SERVICE {"status":"pause","service":"dark_ascendants","uri":"darkascendants/track/55172078","name":"In The Air Tonight (2015 Remastered)","title":"In The Air Tonight (2015 Remastered)","artist":"Phil Collins","album":"Face Value (Deluxe Editon)","albumart":"https://resources.tidal.com/images/468effe2/f73c/4e8b/bcb2/4447c8207f50/1280x1280.jpg","duration":336,"samplerate":"44.1 kHz","bitdepth":"16 bit","trackType":"flac","channels":2,"seek":18000} Mar 27 12:30:23 volumio-ap3 volumio[772]: verbose: CURRENT POSITION 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState stateService pause Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::syncState currentStatus pause Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::pushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreStateMachine::stPlaybackTimer Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ControllerMpd::pushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CoreCommandRouter::servicePushState Mar 27 12:30:23 volumio-ap3 volumio[772]: info: CorePlayQueue::getTrack 0 Mar 27 12:30:23 volumio-ap3 volumio[772]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ------------------------------ 43ms Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ------------------------------ 42ms Mar 27 12:30:23 volumio-ap3 volumio[772]: info: ------------------------------ 39ms Mar 27 12:30:25 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPause Mar 27 12:30:25 volumio-ap3 volumio[772]: info: CoreStateMachine::pause Mar 27 12:30:26 volumio-ap3 volumio[772]: info: CoreCommandRouter::volumioPause Mar 27 12:30:26 volumio-ap3 volumio[772]: info: CoreStateMachine::pause Mar 27 12:30:27 volumio-ap3 volumio[772]: info: Discovery: Remote connection error: 192.168.0.148 Mar 27 12:30:27 volumio-ap3 volumio[772]: info: Discovery: Remote connection error: 192.168.0.148 Mar 27 12:30:27 volumio-ap3 volumio[772]: info: Discovery: Remote connection error: 192.168.0.148 Mar 27 12:30:27 volumio-ap3 volumio[772]: info: Discovery: Remote connection error: 192.168.0.148 Mar 27 12:30:27 volumio-ap3 volumio[772]: info: Discovery: Remote connection error: 192.168.0.148 Mar 27 12:30:27 volumio-ap3 volumio[772]: info: Discovery: Remote connection timeout: 192.168.0.148 Mar 27 12:30:27 volumio-ap3 volumio[772]: info: Discovery: Remote connection timeout: 192.168.0.148 Mar 27 12:30:28 volumio-ap3 volumio[772]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 12:30:28 volumio-ap3 volumio[772]: Error: Unable to resolve or reject the same promise twice Mar 27 12:30:28 volumio-ap3 volumio[772]: at Promise.reject (/data/plugins/music_service/carrot_radio/node_modules/kew/kew.js:189:43) Mar 27 12:30:28 volumio-ap3 volumio[772]: at ClientRequest. (/data/plugins/music_service/carrot_radio/index.js:738:56) Mar 27 12:30:28 volumio-ap3 volumio[772]: at Object.onceWrapper (events.js:421:28) Mar 27 12:30:28 volumio-ap3 volumio[772]: at ClientRequest.emit (events.js:315:20) Mar 27 12:30:28 volumio-ap3 volumio[772]: at TLSSocket.emitRequestTimeout (_http_client.js:784:9) Mar 27 12:30:28 volumio-ap3 volumio[772]: at Object.onceWrapper (events.js:421:28) Mar 27 12:30:28 volumio-ap3 volumio[772]: at TLSSocket.emit (events.js:327:22) Mar 27 12:30:28 volumio-ap3 volumio[772]: at TLSSocket.Socket._onTimeout (net.js:483:8) Mar 27 12:30:28 volumio-ap3 volumio[772]: at listOnTimeout (internal/timers.js:554:17) Mar 27 12:30:28 volumio-ap3 volumio[772]: at processTimers (internal/timers.js:497:7) Mar 27 12:30:28 volumio-ap3 volumio[772]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 12:30:29 volumio-ap3 sudo[3539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-27 12:29 Mar 27 12:30:29 volumio-ap3 sudo[3539]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"