-- Logs begin at Thu 2025-12-04 18:31:05 KST, end at Sun 2026-01-11 15:06:40 KST. -- Jan 11 15:05:09 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 11 15:05:09 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:05:09 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:05:09 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:05:09 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:05:10 hswhdx volumio[9005]: info: browseTIDALUri took 587 milliseconds Jan 11 15:05:10 hswhdx volumio[9005]: info: Preload queue cleared Jan 11 15:05:12 hswhdx volumio[9005]: Searching plugin music_service/tidal Jan 11 15:05:12 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jan 11 15:05:13 hswhdx volumio[9005]: info: searchTIDALUri took 884 milliseconds Jan 11 15:05:13 hswhdx volumio[9005]: info: search took 886 milliseconds Jan 11 15:05:13 hswhdx volumio[9005]: info: All search sources collected, pushing search results Jan 11 15:05:53 hswhdx volumio[9005]: info: Preload queue cleared Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::ClearQueue Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::stop Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::stPlaybackTimer Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::updateTrackBlock Jan 11 15:05:53 hswhdx volumio[9005]: info: CorePlayQueue::getTrackBlock Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::pushState Jan 11 15:05:53 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 0 Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushState Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::serviceStop Jan 11 15:05:53 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 0 Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::serviceStop Jan 11 15:05:53 hswhdx volumio[9005]: info: [1768111553539] ControllerTidal::stop Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 15:05:53 hswhdx volumio[9005]: info: ControllerMpd::stop Jan 11 15:05:53 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:05:53 hswhdx volumio[9005]: info: CorePlayQueue::clearPlayQueue Jan 11 15:05:53 hswhdx volumio[9005]: info: CorePlayQueue::saveQueue Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushQueue Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::addQueueItems Jan 11 15:05:53 hswhdx volumio[9005]: info: CorePlayQueue::addQueueItems Jan 11 15:05:53 hswhdx volumio[9005]: info: Preload queue cleared Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/77611693 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/77611693 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/77611691 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/77611691 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/48928717 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/48928717 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/77611690 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/77611690 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/48928720 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/48928720 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/48928718 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/48928718 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/77611692 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/77611692 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/48928719 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/48928719 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/670684 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/670684 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/670685 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/670685 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/670687 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/670687 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/670686 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/670686 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/16445739 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/16445739 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4793194 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/4793194 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/117831799 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/117831799 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/56442267 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/56442267 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/48359146 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/48359146 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4793192 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/4793192 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/15488565 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/15488565 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4793191 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/4793191 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/413483348 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/413483348 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/330919923 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/330919923 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4793193 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/4793193 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/76125346 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/76125346 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/118999367 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/118999367 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4636256 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/4636256 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/48359149 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/48359149 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/31754656 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/31754656 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/48359147 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/48359147 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4350835 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/4350835 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/3806116 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/3806116 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4350836 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/4350836 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/118999365 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/118999365 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/56442266 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/56442266 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4636257 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/4636257 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/76125345 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/76125345 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/118999366 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/118999366 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/3806119 Jan 11 15:05:53 hswhdx volumio[9005]: info: Exploding uri tidal://song/3806119 in service tidal Jan 11 15:05:53 hswhdx volumio[9005]: info: Jan 11 15:05:53 hswhdx volumio[9005]: ---------------------------- MPD announces state update: player Jan 11 15:05:53 hswhdx volumio[9005]: info: sendMpdCommand stop took 316 milliseconds Jan 11 15:05:53 hswhdx volumio[9005]: info: ControllerMpd::getState Jan 11 15:05:53 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:05:53 hswhdx volumio[9005]: info: sendMpdCommand status took 7 milliseconds Jan 11 15:05:53 hswhdx volumio[9005]: verbose: ControllerMpd::parseState Jan 11 15:05:53 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:05:53 hswhdx volumio[9005]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 15:05:53 hswhdx volumio[9005]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:05:53 hswhdx volumio[9005]: info: ControllerMpd::pushState Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::servicePushState Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::pushState Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushState Jan 11 15:05:53 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 0 Jan 11 15:05:53 hswhdx volumio[9005]: 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":"4325867&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/4325867&soundQuality=HI_RES","trackType":"tidal"} Jan 11 15:05:53 hswhdx volumio[9005]: verbose: CURRENT POSITION 0 Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::syncState stateService stop Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::pushState Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushState Jan 11 15:05:53 hswhdx volumio[9005]: info: No code Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreStateMachine::pushState Jan 11 15:05:53 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushState Jan 11 15:05:53 hswhdx volumio[9005]: info: ------------------------------ 62ms Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1421 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1441 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1437 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1447 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1442 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1438 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1470 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1475 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1468 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1486 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1472 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1497 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1484 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1479 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1485 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1475 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1486 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1485 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1497 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1528 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1524 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1532 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1534 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1535 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1549 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1532 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1566 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1561 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1556 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1552 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1572 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1574 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1607 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1617 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1664 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1678 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1703 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: explodeTIDALUri took 1743 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushQueue Jan 11 15:05:55 hswhdx volumio[9005]: info: CorePlayQueue::saveQueue Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::updateTrackBlock Jan 11 15:05:55 hswhdx volumio[9005]: info: CorePlayQueue::getTrackBlock Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPlay Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::play index 37 Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::addQueueItems Jan 11 15:05:55 hswhdx volumio[9005]: info: CorePlayQueue::addQueueItems Jan 11 15:05:55 hswhdx volumio[9005]: info: Preload queue cleared Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/39599639 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/39599639 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/31040652 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/31040652 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/31754654 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/31754654 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/227733658 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/227733658 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/76125343 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/76125343 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4821026 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/4821026 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/10939604 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/10939604 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/425300621 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/425300621 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/31754653 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/31754653 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4636258 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/4636258 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/31040649 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/31040649 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/4636259 Jan 11 15:05:55 hswhdx volumio[9005]: info: Exploding uri tidal://song/4636259 in service tidal Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::stop Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::play index undefined Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:05:55 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 37 Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::startPlaybackTimer Jan 11 15:05:55 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 37 Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 15:05:55 hswhdx volumio[9005]: info: [1768111555410] ControllerTidal::clearAddPlayTrack Jan 11 15:05:55 hswhdx volumio[9005]: info: Getting stream with soundQuality HI_RES Jan 11 15:05:55 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:05:55 hswhdx volumio[9005]: info: sendMpdCommand stop took 68 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 15:05:55 hswhdx volumio[9005]: info: Jan 11 15:05:55 hswhdx volumio[9005]: ---------------------------- MPD announces system playlist update Jan 11 15:05:55 hswhdx volumio[9005]: info: Ignoring MPD Status Update Jan 11 15:05:55 hswhdx volumio[9005]: info: sendMpdCommand clear took 4 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3806119&soundQuality=HI_RES" Jan 11 15:05:55 hswhdx volumio[9005]: error: updateQueue error: null Jan 11 15:05:55 hswhdx volumio[9005]: info: Jan 11 15:05:55 hswhdx volumio[9005]: ---------------------------- MPD announces system playlist update Jan 11 15:05:55 hswhdx volumio[9005]: info: Ignoring MPD Status Update Jan 11 15:05:55 hswhdx volumio[9005]: info: ------------------------------ 8ms Jan 11 15:05:55 hswhdx volumio[9005]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3806119&soundQuality=HI_RES" took 5 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 15:05:55 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand play Jan 11 15:05:55 hswhdx volumio[9005]: info: ------------------------------ 10ms Jan 11 15:05:55 hswhdx volumio[9005]: info: sendMpdCommand play took 3 milliseconds Jan 11 15:05:55 hswhdx volumio[9005]: STREAMING PROXY: Handling url /?data=tidal://song/3806119&soundQuality=HI_RES Jan 11 15:05:55 hswhdx volumio[9005]: info: Executing endpoint getStreamUrltidal Jan 11 15:05:55 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 634 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 656 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 787 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 836 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 835 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 840 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 839 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 870 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 883 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 902 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 900 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: explodeTIDALUri took 918 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushQueue Jan 11 15:05:56 hswhdx volumio[9005]: info: CorePlayQueue::saveQueue Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreStateMachine::updateTrackBlock Jan 11 15:05:56 hswhdx volumio[9005]: info: CorePlayQueue::getTrackBlock Jan 11 15:05:56 hswhdx volumio[9005]: info: getStreamUrl took 1013 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MWVmOGJjZjFkNWI4NjM5MGNjY2Q2YzMwYTIxZTk0Nl82MS5tcDQ/0.flac?token=1768115156~MmZjZjEwZmJkMzM3NTg3ZTc3MDgzYWMyODA3ZWJjNjIwYWU2NTRjOA== Jan 11 15:05:56 hswhdx volumio[9005]: STREAMING PROXY: Response: 200, length: 53522442 Jan 11 15:05:56 hswhdx volumio[9005]: info: Jan 11 15:05:56 hswhdx volumio[9005]: ---------------------------- MPD announces state update: player Jan 11 15:05:56 hswhdx volumio[9005]: info: ControllerMpd::getState Jan 11 15:05:56 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:05:56 hswhdx volumio[9005]: info: Jan 11 15:05:56 hswhdx volumio[9005]: ---------------------------- MPD announces state update: player Jan 11 15:05:56 hswhdx volumio[9005]: info: ControllerMpd::getState Jan 11 15:05:56 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:05:56 hswhdx volumio[9005]: info: sendMpdCommand status took 6 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: verbose: ControllerMpd::parseState Jan 11 15:05:56 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:05:56 hswhdx volumio[9005]: info: sendMpdCommand status took 10 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: verbose: ControllerMpd::parseState Jan 11 15:05:56 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:05:56 hswhdx volumio[9005]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:05:56 hswhdx volumio[9005]: info: ControllerMpd::pushState Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreCommandRouter::servicePushState Jan 11 15:05:56 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 37 Jan 11 15:05:56 hswhdx volumio[9005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":611,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3806119&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3806119&soundQuality=HI_RES","trackType":"tidal"} Jan 11 15:05:56 hswhdx volumio[9005]: verbose: CURRENT POSITION 37 Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreStateMachine::syncState stateService play Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:05:56 hswhdx volumio[9005]: info: ------------------------------ 35ms Jan 11 15:05:56 hswhdx volumio[9005]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 11 15:05:56 hswhdx volumio[9005]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:05:56 hswhdx volumio[9005]: info: ControllerMpd::pushState Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreCommandRouter::servicePushState Jan 11 15:05:56 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 37 Jan 11 15:05:56 hswhdx volumio[9005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":611,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3806119&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3806119&soundQuality=HI_RES","trackType":"tidal"} Jan 11 15:05:56 hswhdx volumio[9005]: verbose: CURRENT POSITION 37 Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreStateMachine::syncState stateService play Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:05:56 hswhdx volumio[9005]: info: Received an update from plugin. extracting info from payload Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreStateMachine::pushState Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushState Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreStateMachine::pushState Jan 11 15:05:56 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushState Jan 11 15:05:56 hswhdx volumio[9005]: info: ------------------------------ 91ms Jan 11 15:06:01 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 37 Jan 11 15:06:01 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: tidal , goto Jan 11 15:06:01 hswhdx volumio[9005]: info: explodeTIDALUri took 603 milliseconds Jan 11 15:06:02 hswhdx volumio[9005]: info: browseTIDALUri took 663 milliseconds Jan 11 15:06:02 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:06:02 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:06:04 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:06:04 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:06:05 hswhdx volumio[9005]: info: Executing endpoint metavolumio Jan 11 15:06:05 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 15:06:08 hswhdx volumio[9005]: STREAMING PROXY: Client dropped request, destroying Jan 11 15:06:18 hswhdx volumio[9005]: info: Preload queue cleared Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::ClearQueue Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::stop Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::stPlaybackTimer Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::updateTrackBlock Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::getTrackBlock Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::pushState Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 37 Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushState Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::serviceStop Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 37 Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::serviceStop Jan 11 15:06:18 hswhdx volumio[9005]: info: [1768111578930] ControllerTidal::stop Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 15:06:18 hswhdx volumio[9005]: info: ControllerMpd::stop Jan 11 15:06:18 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::clearPlayQueue Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::saveQueue Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushQueue Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::addQueueItems Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::addQueueItems Jan 11 15:06:18 hswhdx volumio[9005]: info: Preload queue cleared Jan 11 15:06:18 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/3806116 Jan 11 15:06:18 hswhdx volumio[9005]: info: Using cached record of: tidal://song/3806116 Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushQueue Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::saveQueue Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::updateTrackBlock Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::getTrackBlock Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPlay Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::play index 0 Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::addQueueItems Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::addQueueItems Jan 11 15:06:18 hswhdx volumio[9005]: info: Preload queue cleared Jan 11 15:06:18 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/3806117 Jan 11 15:06:18 hswhdx volumio[9005]: info: Exploding uri tidal://song/3806117 in service tidal Jan 11 15:06:18 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/3806118 Jan 11 15:06:18 hswhdx volumio[9005]: info: Exploding uri tidal://song/3806118 in service tidal Jan 11 15:06:18 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/3806119 Jan 11 15:06:18 hswhdx volumio[9005]: info: Using cached record of: tidal://song/3806119 Jan 11 15:06:18 hswhdx volumio[9005]: info: Adding Item to queue: tidal://song/3806120 Jan 11 15:06:18 hswhdx volumio[9005]: info: Exploding uri tidal://song/3806120 in service tidal Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::stop Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::play index undefined Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 0 Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreStateMachine::startPlaybackTimer Jan 11 15:06:18 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 0 Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 15:06:18 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 15:06:18 hswhdx volumio[9005]: info: [1768111578968] ControllerTidal::clearAddPlayTrack Jan 11 15:06:18 hswhdx volumio[9005]: info: Getting stream with soundQuality HI_RES Jan 11 15:06:18 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 15:06:18 hswhdx volumio[9005]: info: Jan 11 15:06:18 hswhdx volumio[9005]: ---------------------------- MPD announces state update: player Jan 11 15:06:18 hswhdx volumio[9005]: info: sendMpdCommand stop took 61 milliseconds Jan 11 15:06:18 hswhdx volumio[9005]: info: ControllerMpd::getState Jan 11 15:06:18 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:06:18 hswhdx volumio[9005]: info: sendMpdCommand stop took 24 milliseconds Jan 11 15:06:18 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 15:06:18 hswhdx volumio[9005]: info: Jan 11 15:06:18 hswhdx volumio[9005]: ---------------------------- MPD announces system playlist update Jan 11 15:06:18 hswhdx volumio[9005]: info: Ignoring MPD Status Update Jan 11 15:06:18 hswhdx volumio[9005]: info: sendMpdCommand status took 3 milliseconds Jan 11 15:06:18 hswhdx volumio[9005]: info: sendMpdCommand clear took 2 milliseconds Jan 11 15:06:18 hswhdx volumio[9005]: verbose: ControllerMpd::parseState Jan 11 15:06:18 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:06:18 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3806116&soundQuality=HI_RES" Jan 11 15:06:19 hswhdx volumio[9005]: error: updateQueue error: null Jan 11 15:06:19 hswhdx volumio[9005]: info: Jan 11 15:06:19 hswhdx volumio[9005]: ---------------------------- MPD announces system playlist update Jan 11 15:06:19 hswhdx volumio[9005]: info: Ignoring MPD Status Update Jan 11 15:06:19 hswhdx volumio[9005]: info: ------------------------------ 7ms Jan 11 15:06:19 hswhdx volumio[9005]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 15:06:19 hswhdx volumio[9005]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/3806116&soundQuality=HI_RES" took 5 milliseconds Jan 11 15:06:19 hswhdx volumio[9005]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:06:19 hswhdx volumio[9005]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 15:06:19 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand play Jan 11 15:06:19 hswhdx volumio[9005]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 15:06:19 hswhdx volumio[9005]: info: ------------------------------ 15ms Jan 11 15:06:19 hswhdx volumio[9005]: STREAMING PROXY: Handling url /?data=tidal://song/3806116&soundQuality=HI_RES Jan 11 15:06:19 hswhdx volumio[9005]: info: ------------------------------ 8ms Jan 11 15:06:19 hswhdx volumio[9005]: info: sendMpdCommand play took 4 milliseconds Jan 11 15:06:19 hswhdx volumio[9005]: info: Executing endpoint getStreamUrltidal Jan 11 15:06:19 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 11 15:06:19 hswhdx volumio[9005]: info: explodeTIDALUri took 845 milliseconds Jan 11 15:06:19 hswhdx volumio[9005]: info: explodeTIDALUri took 860 milliseconds Jan 11 15:06:19 hswhdx volumio[9005]: info: explodeTIDALUri took 910 milliseconds Jan 11 15:06:19 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushQueue Jan 11 15:06:19 hswhdx volumio[9005]: info: CorePlayQueue::saveQueue Jan 11 15:06:19 hswhdx volumio[9005]: info: CoreStateMachine::updateTrackBlock Jan 11 15:06:19 hswhdx volumio[9005]: info: CorePlayQueue::getTrackBlock Jan 11 15:06:20 hswhdx volumio[9005]: info: getStreamUrl took 1003 milliseconds Jan 11 15:06:20 hswhdx volumio[9005]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidlMzRlZmM2NGEwNWVjN2FlZjdjNGMzOTI1ZmVlMjc3NV82MS5tcDQ/0.flac?token=1768115179~ZmJiOTc0ZDYyNjhiMjQ4N2RmYjAzNzk0NzI2Y2Y1MTI5Mzc4ZDI3NQ== Jan 11 15:06:20 hswhdx volumio[9005]: STREAMING PROXY: Response: 200, length: 75776069 Jan 11 15:06:20 hswhdx volumio[9005]: info: Jan 11 15:06:20 hswhdx volumio[9005]: ---------------------------- MPD announces state update: player Jan 11 15:06:20 hswhdx volumio[9005]: info: ControllerMpd::getState Jan 11 15:06:20 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:06:20 hswhdx volumio[9005]: info: Jan 11 15:06:20 hswhdx volumio[9005]: ---------------------------- MPD announces state update: player Jan 11 15:06:20 hswhdx volumio[9005]: info: ControllerMpd::getState Jan 11 15:06:20 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:06:20 hswhdx volumio[9005]: info: sendMpdCommand status took 6 milliseconds Jan 11 15:06:20 hswhdx volumio[9005]: verbose: ControllerMpd::parseState Jan 11 15:06:20 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:06:20 hswhdx volumio[9005]: info: sendMpdCommand status took 8 milliseconds Jan 11 15:06:20 hswhdx volumio[9005]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 11 15:06:20 hswhdx volumio[9005]: verbose: ControllerMpd::parseState Jan 11 15:06:20 hswhdx volumio[9005]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:06:20 hswhdx volumio[9005]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:06:20 hswhdx volumio[9005]: info: ControllerMpd::pushState Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreCommandRouter::servicePushState Jan 11 15:06:20 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 0 Jan 11 15:06:20 hswhdx volumio[9005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":982,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"3806116&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3806116&soundQuality=HI_RES","trackType":"tidal"} Jan 11 15:06:20 hswhdx volumio[9005]: verbose: CURRENT POSITION 0 Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreStateMachine::syncState stateService play Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreStateMachine::syncState currentStatus stop Jan 11 15:06:20 hswhdx volumio[9005]: info: ------------------------------ 29ms Jan 11 15:06:20 hswhdx volumio[9005]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 11 15:06:20 hswhdx volumio[9005]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:06:20 hswhdx volumio[9005]: info: ControllerMpd::pushState Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreCommandRouter::servicePushState Jan 11 15:06:20 hswhdx volumio[9005]: info: CorePlayQueue::getTrack 0 Jan 11 15:06:20 hswhdx volumio[9005]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":982,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"288 Kbps","isStreaming":false,"title":"3806116&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/3806116&soundQuality=HI_RES","trackType":"tidal"} Jan 11 15:06:20 hswhdx volumio[9005]: verbose: CURRENT POSITION 0 Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreStateMachine::syncState stateService play Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:06:20 hswhdx volumio[9005]: info: Received an update from plugin. extracting info from payload Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreStateMachine::pushState Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushState Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreStateMachine::pushState Jan 11 15:06:20 hswhdx volumio[9005]: info: CoreCommandRouter::volumioPushState Jan 11 15:06:20 hswhdx volumio[9005]: info: ------------------------------ 74ms Jan 11 15:06:39 hswhdx volumio[9005]: STREAMING PROXY: Client dropped request, destroying Jan 11 15:06:40 hswhdx volumio[9005]: info: CoreCommandRouter::volumioSaveQueueToPlaylist Jan 11 15:06:40 hswhdx volumio[9005]: info: CoreStateMachine::getQueue Jan 11 15:06:40 hswhdx volumio[9005]: info: CorePlayQueue::getQueue Jan 11 15:06:40 hswhdx volumio[9005]: info: Saving Cloud item Shostakovich Symphony No.5 in D minor, Op.47 Jan 11 15:06:40 hswhdx volumio[9005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , saveCloudItem Jan 11 15:06:40 hswhdx volumio[9005]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 15:06:40 hswhdx volumio[9005]: Error: Reference.child failed: First argument was an invalid path = "/user_data/Y1tqxwgnPBO41HjZ1ERZzk9xxef1/myPlaylists/Shostakovich Symphony No.5 in D minor, Op.47". Paths must be non-empty strings and can't contain ".", "#", "$", "[", or "]" Jan 11 15:06:40 hswhdx volumio[9005]: at validatePathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1667:15) Jan 11 15:06:40 hswhdx volumio[9005]: at validateRootPathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1679:5) Jan 11 15:06:40 hswhdx volumio[9005]: at Reference.child (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:13737:17) Jan 11 15:06:40 hswhdx volumio[9005]: at Database.ref (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:15115:48) Jan 11 15:06:40 hswhdx volumio[9005]: at myVolumio.syncJSONToCloud (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:28817) Jan 11 15:06:40 hswhdx volumio[9005]: at myVolumio.saveCloudMyPlaylist (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:23840) Jan 11 15:06:40 hswhdx volumio[9005]: at myVolumio.saveCloudItem (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18337) Jan 11 15:06:40 hswhdx volumio[9005]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jan 11 15:06:40 hswhdx volumio[9005]: at PlaylistManager.saveJSONFile (/volumio/app/playlistManager.js:613:31) Jan 11 15:06:40 hswhdx volumio[9005]: at PlaylistManager.commonAddItemsToPlaylist (/volumio/app/playlistManager.js:834:8) Jan 11 15:06:40 hswhdx volumio[9005]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 15:06:40 hswhdx sudo[9888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 15:05 Jan 11 15:06:40 hswhdx sudo[9888]: 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"