-- Logs begin at Thu 2019-02-14 19:11:59 KST, end at Sun 2025-12-28 23:08:32 KST. -- Dec 28 23:07:03 hswhdx volumio[781]: info: Preload queue cleared Dec 28 23:07:03 hswhdx volumio[781]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:07:03 hswhdx volumio[781]: info: CoreStateMachine::ClearQueue Dec 28 23:07:03 hswhdx volumio[781]: info: CoreStateMachine::stop Dec 28 23:07:03 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:07:03 hswhdx volumio[781]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:07:03 hswhdx volumio[781]: info: CoreStateMachine::updateTrackBlock Dec 28 23:07:03 hswhdx volumio[781]: info: CorePlayQueue::getTrackBlock Dec 28 23:07:03 hswhdx volumio[781]: info: CoreStateMachine::pushState Dec 28 23:07:03 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:03 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:07:03 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushState Dec 28 23:07:03 hswhdx volumio[781]: info: CoreStateMachine::serviceStop Dec 28 23:07:03 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:03 hswhdx volumio[781]: info: CoreCommandRouter::serviceStop Dec 28 23:07:03 hswhdx volumio[781]: info: [1766930823986] ControllerWebradio::stop Dec 28 23:07:03 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:07:03 hswhdx volumio[781]: info: CorePlayQueue::clearPlayQueue Dec 28 23:07:03 hswhdx volumio[781]: info: CorePlayQueue::saveQueue Dec 28 23:07:03 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:07:03 hswhdx volumio[781]: info: CoreStateMachine::addQueueItems Dec 28 23:07:03 hswhdx volumio[781]: info: CorePlayQueue::addQueueItems Dec 28 23:07:03 hswhdx volumio[781]: info: Preload queue cleared Dec 28 23:07:03 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/428102860 Dec 28 23:07:03 hswhdx volumio[781]: info: Exploding uri tidal://song/428102860 in service tidal Dec 28 23:07:03 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825879 Dec 28 23:07:03 hswhdx volumio[781]: info: Exploding uri tidal://song/44825879 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Dec 28 23:07:04 hswhdx volumio[781]: ---------------------------- MPD announces state update: player Dec 28 23:07:04 hswhdx volumio[781]: info: sendMpdCommand stop took 53 milliseconds Dec 28 23:07:04 hswhdx volumio[781]: info: ControllerMpd::getState Dec 28 23:07:04 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:07:04 hswhdx volumio[781]: info: sendMpdCommand status took 3 milliseconds Dec 28 23:07:04 hswhdx volumio[781]: verbose: ControllerMpd::parseState Dec 28 23:07:04 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:07:04 hswhdx volumio[781]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 23:07:04 hswhdx volumio[781]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:07:04 hswhdx volumio[781]: info: ControllerMpd::pushState Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::servicePushState Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::pushState Dec 28 23:07:04 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushState Dec 28 23:07:04 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:04 hswhdx volumio[781]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjcwODYzNjl9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=JVJ8x-9vGIR5gu17TqEyLP2QYgEGN~l-x6vrJnGda~ss8tX1~KrdXAD73lBwCLzieAnLcA7SW3x0B97mMOHJiXPbTo7NiJytK6GH~D0~UtE62aKAfQFP3JXaeRfyG6brdUzZILU1BLiGqBTgM44Gi4ViVzrS93wpa6Gnyq~hBVPzGMcOShnLi1Kown1F9toJwY~9Xj2X0LDu7xL5CsWuW2g3eHZL2MKYkhleIzprwdntloW2iAk1yA8-MwOhmTgpuRDTAceF52ALZSJXWfpkuJXdBLiLYM2R3-OxJN92oPQEapF41i9eg2~Hp~zspVOcYHZV4LFB0ojEBqvPL1NGfg__","trackType":""} Dec 28 23:07:04 hswhdx volumio[781]: verbose: CURRENT POSITION 0 Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::syncState stateService stop Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::pushState Dec 28 23:07:04 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushState Dec 28 23:07:04 hswhdx volumio[781]: info: No code Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::pushState Dec 28 23:07:04 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushState Dec 28 23:07:04 hswhdx volumio[781]: info: ------------------------------ 33ms Dec 28 23:07:04 hswhdx volumio[781]: info: explodeTIDALUri took 609 milliseconds Dec 28 23:07:04 hswhdx volumio[781]: info: explodeTIDALUri took 628 milliseconds Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:07:04 hswhdx volumio[781]: info: CorePlayQueue::saveQueue Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::updateTrackBlock Dec 28 23:07:04 hswhdx volumio[781]: info: CorePlayQueue::getTrackBlock Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::volumioPlay Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::play index 1 Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::addQueueItems Dec 28 23:07:04 hswhdx volumio[781]: info: CorePlayQueue::addQueueItems Dec 28 23:07:04 hswhdx volumio[781]: info: Preload queue cleared Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/432053295 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/432053295 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825900 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825900 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/432053297 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/432053297 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/432053299 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/432053299 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825880 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825880 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/432053300 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/432053300 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825882 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825882 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825881 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825881 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825884 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825884 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825883 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825883 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825887 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825887 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/45264641 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/45264641 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825888 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825888 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825893 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825893 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825886 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825886 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825889 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825889 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825890 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825890 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825885 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825885 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/45264642 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/45264642 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825892 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825892 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825891 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825891 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825896 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825896 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825898 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825898 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825895 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825895 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/45264644 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/45264644 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/306158223 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/306158223 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825901 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825901 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825894 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825894 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/45264648 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/45264648 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/45264649 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/45264649 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825897 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825897 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825899 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825899 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/45264647 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/45264647 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825902 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/44825902 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/306158231 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/306158231 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/306158271 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/306158271 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/306158226 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/306158226 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/45264643 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/45264643 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/306158189 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/306158189 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/306158195 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/306158195 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/306158214 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/306158214 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/45264645 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/45264645 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/432065843 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/432065843 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/432065844 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/432065844 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/45264646 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/45264646 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/432065847 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/432065847 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/306158178 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/306158178 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/306158204 Dec 28 23:07:04 hswhdx volumio[781]: info: Exploding uri tidal://song/306158204 in service tidal Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::stop Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::play index undefined Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:07:04 hswhdx volumio[781]: info: CorePlayQueue::getTrack 1 Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:07:04 hswhdx volumio[781]: info: CorePlayQueue::getTrack 1 Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 23:07:04 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 23:07:04 hswhdx volumio[781]: info: [1766930824713] ControllerTidal::clearAddPlayTrack Dec 28 23:07:04 hswhdx volumio[781]: info: Getting stream with soundQuality HI_RES Dec 28 23:07:04 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:07:04 hswhdx volumio[781]: info: sendMpdCommand stop took 197 milliseconds Dec 28 23:07:04 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:07:04 hswhdx volumio[781]: info: Dec 28 23:07:04 hswhdx volumio[781]: ---------------------------- MPD announces system playlist update Dec 28 23:07:04 hswhdx volumio[781]: info: Ignoring MPD Status Update Dec 28 23:07:04 hswhdx volumio[781]: info: sendMpdCommand clear took 34 milliseconds Dec 28 23:07:04 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/44825879&soundQuality=HI_RES" Dec 28 23:07:04 hswhdx volumio[781]: error: updateQueue error: null Dec 28 23:07:04 hswhdx volumio[781]: info: Dec 28 23:07:04 hswhdx volumio[781]: ---------------------------- MPD announces system playlist update Dec 28 23:07:04 hswhdx volumio[781]: info: Ignoring MPD Status Update Dec 28 23:07:04 hswhdx volumio[781]: info: ------------------------------ 19ms Dec 28 23:07:04 hswhdx volumio[781]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/44825879&soundQuality=HI_RES" took 6 milliseconds Dec 28 23:07:04 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:07:04 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:07:04 hswhdx volumio[781]: info: ------------------------------ 8ms Dec 28 23:07:04 hswhdx volumio[781]: info: sendMpdCommand play took 8 milliseconds Dec 28 23:07:05 hswhdx volumio[781]: STREAMING PROXY: Handling url /?data=tidal://song/44825879&soundQuality=HI_RES Dec 28 23:07:05 hswhdx volumio[781]: info: Executing endpoint getStreamUrltidal Dec 28 23:07:05 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1679 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1668 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1682 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1696 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1692 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1683 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1710 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1714 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1697 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1700 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1705 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1717 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1699 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1720 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1706 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1713 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1752 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1731 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1755 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1759 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1742 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1746 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1760 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1765 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1764 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1747 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1789 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1761 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1770 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1800 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1810 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1808 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1815 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1805 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1805 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1807 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1829 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1814 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1818 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1824 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1843 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1849 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1861 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1879 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1898 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1939 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: explodeTIDALUri took 1957 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:07:06 hswhdx volumio[781]: info: CorePlayQueue::saveQueue Dec 28 23:07:06 hswhdx volumio[781]: info: CoreStateMachine::updateTrackBlock Dec 28 23:07:06 hswhdx volumio[781]: info: CorePlayQueue::getTrackBlock Dec 28 23:07:06 hswhdx volumio[781]: info: getStreamUrl took 1707 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1NTc5YzQ0MWY1MDY4YjYwMzBlNDI1ODdkMmZlMmUwOV82MS5tcDQ/0.flac?token=1766934426~MTAwZTBlOTk2YmE4MTBjOTVkMzBkMGE0YmU0YTI3MzUwMzgxNmQyNg== Dec 28 23:07:06 hswhdx volumio[781]: STREAMING PROXY: Response: 200, length: 20070680 Dec 28 23:07:06 hswhdx volumio[781]: info: Dec 28 23:07:06 hswhdx volumio[781]: ---------------------------- MPD announces state update: player Dec 28 23:07:06 hswhdx volumio[781]: info: ControllerMpd::getState Dec 28 23:07:06 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:07:06 hswhdx volumio[781]: info: Dec 28 23:07:06 hswhdx volumio[781]: ---------------------------- MPD announces state update: player Dec 28 23:07:06 hswhdx volumio[781]: info: ControllerMpd::getState Dec 28 23:07:06 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:07:06 hswhdx volumio[781]: info: sendMpdCommand status took 14 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: verbose: ControllerMpd::parseState Dec 28 23:07:06 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:07:06 hswhdx volumio[781]: info: sendMpdCommand status took 13 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: verbose: ControllerMpd::parseState Dec 28 23:07:06 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:07:06 hswhdx volumio[781]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:07:06 hswhdx volumio[781]: info: ControllerMpd::pushState Dec 28 23:07:06 hswhdx volumio[781]: info: CoreCommandRouter::servicePushState Dec 28 23:07:06 hswhdx volumio[781]: info: CorePlayQueue::getTrack 1 Dec 28 23:07:06 hswhdx volumio[781]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"208 Kbps","isStreaming":false,"title":"44825879&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/44825879&soundQuality=HI_RES","trackType":"tidal"} Dec 28 23:07:06 hswhdx volumio[781]: verbose: CURRENT POSITION 1 Dec 28 23:07:06 hswhdx volumio[781]: info: CoreStateMachine::syncState stateService play Dec 28 23:07:06 hswhdx volumio[781]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:07:06 hswhdx volumio[781]: info: ------------------------------ 34ms Dec 28 23:07:06 hswhdx volumio[781]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 28 23:07:06 hswhdx volumio[781]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:07:06 hswhdx volumio[781]: info: ControllerMpd::pushState Dec 28 23:07:06 hswhdx volumio[781]: info: CoreCommandRouter::servicePushState Dec 28 23:07:06 hswhdx volumio[781]: info: CorePlayQueue::getTrack 1 Dec 28 23:07:06 hswhdx volumio[781]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"208 Kbps","isStreaming":false,"title":"44825879&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/44825879&soundQuality=HI_RES","trackType":"tidal"} Dec 28 23:07:06 hswhdx volumio[781]: verbose: CURRENT POSITION 1 Dec 28 23:07:06 hswhdx volumio[781]: info: CoreStateMachine::syncState stateService play Dec 28 23:07:06 hswhdx volumio[781]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:07:06 hswhdx volumio[781]: info: Received an update from plugin. extracting info from payload Dec 28 23:07:06 hswhdx volumio[781]: info: CoreStateMachine::pushState Dec 28 23:07:07 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:07:07 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushState Dec 28 23:07:07 hswhdx volumio[781]: info: CoreStateMachine::pushState Dec 28 23:07:07 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushState Dec 28 23:07:07 hswhdx volumio[781]: info: ------------------------------ 61ms Dec 28 23:07:08 hswhdx volumio[781]: STREAMING PROXY: Client dropped request, destroying Dec 28 23:07:15 hswhdx volumio[781]: info: CorePlayQueue::getTrack 1 Dec 28 23:07:15 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: tidal , goto Dec 28 23:07:16 hswhdx volumio[781]: info: explodeTIDALUri took 539 milliseconds Dec 28 23:07:17 hswhdx volumio[781]: info: browseTIDALUri took 607 milliseconds Dec 28 23:07:17 hswhdx volumio[781]: info: Executing endpoint metavolumio Dec 28 23:07:17 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 23:07:17 hswhdx volumio[781]: info: Executing endpoint metavolumio Dec 28 23:07:17 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 23:07:17 hswhdx volumio[781]: info: Executing endpoint metavolumio Dec 28 23:07:17 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 23:07:17 hswhdx volumio[781]: info: Executing endpoint metavolumio Dec 28 23:07:17 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 23:07:17 hswhdx volumio[781]: info: Executing endpoint metavolumio Dec 28 23:07:17 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 23:07:17 hswhdx volumio[781]: info: Executing endpoint metavolumio Dec 28 23:07:17 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 23:07:29 hswhdx volumio[781]: info: Preload queue cleared Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::ClearQueue Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::stop Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::stPlaybackTimer Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::updateTrackBlock Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::getTrackBlock Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::pushState Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::getTrack 1 Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushState Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::serviceStop Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::getTrack 1 Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::serviceStop Dec 28 23:07:29 hswhdx volumio[781]: info: [1766930849143] ControllerTidal::stop Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:07:29 hswhdx volumio[781]: info: ControllerMpd::stop Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::clearPlayQueue Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::saveQueue Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::addQueueItems Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::addQueueItems Dec 28 23:07:29 hswhdx volumio[781]: info: Preload queue cleared Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825879 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825879 Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::saveQueue Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::updateTrackBlock Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::getTrackBlock Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::volumioPlay Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::play index 0 Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::addQueueItems Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::addQueueItems Dec 28 23:07:29 hswhdx volumio[781]: info: Preload queue cleared Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825880 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825880 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825881 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825881 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825882 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825882 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825883 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825883 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825884 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825884 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825885 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825885 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825886 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825886 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825887 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825887 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825888 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825888 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825889 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825889 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825890 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825890 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825891 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825891 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825892 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825892 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825893 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825893 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825894 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825894 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825895 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825895 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825896 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825896 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825897 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825897 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825898 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825898 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825899 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825899 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825900 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825900 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825901 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825901 Dec 28 23:07:29 hswhdx volumio[781]: info: Adding Item to queue: tidal://song/44825902 Dec 28 23:07:29 hswhdx volumio[781]: info: Using cached record of: tidal://song/44825902 Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::stop Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushQueue Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::saveQueue Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::play index undefined Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::updateTrackBlock Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::getTrackBlock Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::startPlaybackTimer Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:29 hswhdx volumio[781]: info: [1766930849194] ControllerTidal::clearAddPlayTrack Dec 28 23:07:29 hswhdx volumio[781]: info: Getting stream with soundQuality HI_RES Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 23:07:29 hswhdx volumio[781]: info: Dec 28 23:07:29 hswhdx volumio[781]: ---------------------------- MPD announces state update: player Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand stop took 64 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: info: ControllerMpd::getState Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand stop took 16 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 23:07:29 hswhdx volumio[781]: info: Dec 28 23:07:29 hswhdx volumio[781]: ---------------------------- MPD announces system playlist update Dec 28 23:07:29 hswhdx volumio[781]: info: Ignoring MPD Status Update Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand status took 5 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand clear took 4 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::parseState Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/44825879&soundQuality=HI_RES" Dec 28 23:07:29 hswhdx volumio[781]: error: updateQueue error: null Dec 28 23:07:29 hswhdx volumio[781]: info: Dec 28 23:07:29 hswhdx volumio[781]: ---------------------------- MPD announces system playlist update Dec 28 23:07:29 hswhdx volumio[781]: info: Ignoring MPD Status Update Dec 28 23:07:29 hswhdx volumio[781]: info: ------------------------------ 8ms Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/44825879&soundQuality=HI_RES" took 4 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand play Dec 28 23:07:29 hswhdx volumio[781]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 28 23:07:29 hswhdx volumio[781]: info: ------------------------------ 17ms Dec 28 23:07:29 hswhdx volumio[781]: info: ------------------------------ 7ms Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand play took 4 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: STREAMING PROXY: Handling url /?data=tidal://song/44825879&soundQuality=HI_RES Dec 28 23:07:29 hswhdx volumio[781]: info: Executing endpoint getStreamUrltidal Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Dec 28 23:07:29 hswhdx volumio[781]: info: getStreamUrl took 592 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1NTc5YzQ0MWY1MDY4YjYwMzBlNDI1ODdkMmZlMmUwOV82MS5tcDQ/0.flac?token=1766934426~MTAwZTBlOTk2YmE4MTBjOTVkMzBkMGE0YmU0YTI3MzUwMzgxNmQyNg== Dec 28 23:07:29 hswhdx volumio[781]: STREAMING PROXY: Response: 200, length: 20070680 Dec 28 23:07:29 hswhdx volumio[781]: info: Dec 28 23:07:29 hswhdx volumio[781]: ---------------------------- MPD announces state update: player Dec 28 23:07:29 hswhdx volumio[781]: info: ControllerMpd::getState Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:07:29 hswhdx volumio[781]: info: Dec 28 23:07:29 hswhdx volumio[781]: ---------------------------- MPD announces state update: player Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand status took 3 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: info: ControllerMpd::getState Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand status Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::parseState Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand status took 5 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::parseState Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:07:29 hswhdx volumio[781]: info: ControllerMpd::pushState Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::servicePushState Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:29 hswhdx volumio[781]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"44825879&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/44825879&soundQuality=HI_RES","trackType":"tidal"} Dec 28 23:07:29 hswhdx volumio[781]: verbose: CURRENT POSITION 0 Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::syncState stateService play Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::syncState currentStatus stop Dec 28 23:07:29 hswhdx volumio[781]: info: ------------------------------ 23ms Dec 28 23:07:29 hswhdx volumio[781]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 28 23:07:29 hswhdx volumio[781]: verbose: ControllerMpd::parseTrackInfo Dec 28 23:07:29 hswhdx volumio[781]: info: ControllerMpd::pushState Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::servicePushState Dec 28 23:07:29 hswhdx volumio[781]: info: CorePlayQueue::getTrack 0 Dec 28 23:07:29 hswhdx volumio[781]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":491,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"189 Kbps","isStreaming":false,"title":"44825879&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/44825879&soundQuality=HI_RES","trackType":"tidal"} Dec 28 23:07:29 hswhdx volumio[781]: verbose: CURRENT POSITION 0 Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::syncState stateService play Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::syncState currentStatus play Dec 28 23:07:29 hswhdx volumio[781]: info: Received an update from plugin. extracting info from payload Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::pushState Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushState Dec 28 23:07:29 hswhdx volumio[781]: info: CoreStateMachine::pushState Dec 28 23:07:29 hswhdx volumio[781]: info: CoreCommandRouter::volumioPushState Dec 28 23:07:29 hswhdx volumio[781]: info: ------------------------------ 39ms Dec 28 23:07:31 hswhdx volumio[781]: STREAMING PROXY: Client dropped request, destroying Dec 28 23:07:41 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 23:07:41 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 23:07:41 hswhdx volumio[781]: info: Discovery: Getting this device information Dec 28 23:07:41 hswhdx volumio[781]: info: CoreCommandRouter::volumioGetState Dec 28 23:07:41 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 23:07:41 hswhdx volumio[781]: verbose: New Socket.io Connection to 192.168.50.160:3000 from 192.168.50.173 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 28 23:07:41 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 28 23:07:41 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 28 23:08:32 hswhdx volumio[781]: info: CoreCommandRouter::volumioSaveQueueToPlaylist Dec 28 23:08:32 hswhdx volumio[781]: info: CoreStateMachine::getQueue Dec 28 23:08:32 hswhdx volumio[781]: info: CorePlayQueue::getQueue Dec 28 23:08:32 hswhdx volumio[781]: info: Saving Cloud item Shostakovich 24 Preludes and Fugues Op. 87 Dec 28 23:08:32 hswhdx volumio[781]: info: CoreCommandRouter::executeOnPlugin: my_volumio , saveCloudItem Dec 28 23:08:32 hswhdx volumio[781]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 23:08:32 hswhdx volumio[781]: Error: Reference.child failed: First argument was an invalid path = "/user_data/Y1tqxwgnPBO41HjZ1ERZzk9xxef1/myPlaylists/Shostakovich 24 Preludes and Fugues Op. 87". Paths must be non-empty strings and can't contain ".", "#", "$", "[", or "]" Dec 28 23:08:32 hswhdx volumio[781]: at validatePathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1667:15) Dec 28 23:08:32 hswhdx volumio[781]: at validateRootPathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1679:5) Dec 28 23:08:32 hswhdx volumio[781]: at Reference.child (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:13737:17) Dec 28 23:08:32 hswhdx volumio[781]: at Database.ref (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:15115:48) Dec 28 23:08:32 hswhdx volumio[781]: at myVolumio.syncJSONToCloud (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:28817) Dec 28 23:08:32 hswhdx volumio[781]: at myVolumio.saveCloudMyPlaylist (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:23840) Dec 28 23:08:32 hswhdx volumio[781]: at myVolumio.saveCloudItem (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18337) Dec 28 23:08:32 hswhdx volumio[781]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 28 23:08:32 hswhdx volumio[781]: at PlaylistManager.saveJSONFile (/volumio/app/playlistManager.js:613:31) Dec 28 23:08:32 hswhdx volumio[781]: at PlaylistManager.commonAddItemsToPlaylist (/volumio/app/playlistManager.js:834:8) Dec 28 23:08:32 hswhdx volumio[781]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 23:08:32 hswhdx sudo[5351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 23:07 Dec 28 23:08:32 hswhdx sudo[5351]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"