-- Logs begin at Wed 2024-06-05 03:46:45 KST, end at Wed 2024-07-10 15:26:30 KST. -- Jul 10 15:25:00 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:01 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jul 10 15:25:01 volumio volumio[925]: info: Preload queue cleared Jul 10 15:25:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jul 10 15:25:03 volumio volumio[925]: info: Preload queue cleared Jul 10 15:25:03 volumio volumio[925]: info: Preloading song: webkbs/0 Jul 10 15:25:03 volumio volumio[925]: info: Preloading song: webkbs/1 Jul 10 15:25:03 volumio volumio[925]: info: Preloading song: webkbs/2 Jul 10 15:25:03 volumio volumio[925]: info: Preloading song: webkbs/3 Jul 10 15:25:03 volumio volumio[925]: info: Preloading song: webkbs/4 Jul 10 15:25:03 volumio volumio[925]: info: Preloading song: webkbs/5 Jul 10 15:25:03 volumio volumio[925]: info: Exploding uri webkbs/0 in service personal_radio Jul 10 15:25:03 volumio volumio[925]: info: Exploding uri webkbs/1 in service personal_radio Jul 10 15:25:03 volumio volumio[925]: info: Exploding uri webkbs/2 in service personal_radio Jul 10 15:25:03 volumio volumio[925]: info: Exploding uri webkbs/3 in service personal_radio Jul 10 15:25:03 volumio volumio[925]: info: Exploding uri webkbs/4 in service personal_radio Jul 10 15:25:03 volumio volumio[925]: info: Exploding uri webkbs/5 in service personal_radio Jul 10 15:25:04 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:05 volumio volumio[925]: info: Preload queue cleared Jul 10 15:25:05 volumio volumio[925]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 10 15:25:05 volumio volumio[925]: info: CoreStateMachine::ClearQueue Jul 10 15:25:05 volumio volumio[925]: info: CoreStateMachine::stop Jul 10 15:25:05 volumio volumio[925]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:25:05 volumio volumio[925]: info: CorePlayQueue::clearPlayQueue Jul 10 15:25:05 volumio volumio[925]: info: CorePlayQueue::saveQueue Jul 10 15:25:05 volumio volumio[925]: info: CoreCommandRouter::volumioPushQueue Jul 10 15:25:05 volumio volumio[925]: info: CoreStateMachine::addQueueItems Jul 10 15:25:05 volumio volumio[925]: info: CorePlayQueue::addQueueItems Jul 10 15:25:05 volumio volumio[925]: info: Preload queue cleared Jul 10 15:25:05 volumio volumio[925]: info: Adding Item to queue: webkbs/0 Jul 10 15:25:05 volumio volumio[925]: info: Using cached record of: webkbs/0 Jul 10 15:25:05 volumio volumio[925]: info: Adding Item to queue: webkbs/1 Jul 10 15:25:05 volumio volumio[925]: info: Using cached record of: webkbs/1 Jul 10 15:25:05 volumio volumio[925]: info: Adding Item to queue: webkbs/2 Jul 10 15:25:05 volumio volumio[925]: info: Using cached record of: webkbs/2 Jul 10 15:25:05 volumio volumio[925]: info: Adding Item to queue: webkbs/3 Jul 10 15:25:05 volumio volumio[925]: info: Using cached record of: webkbs/3 Jul 10 15:25:05 volumio volumio[925]: info: Adding Item to queue: webkbs/4 Jul 10 15:25:05 volumio volumio[925]: info: Using cached record of: webkbs/4 Jul 10 15:25:05 volumio volumio[925]: info: Adding Item to queue: webkbs/5 Jul 10 15:25:05 volumio volumio[925]: info: Using cached record of: webkbs/5 Jul 10 15:25:05 volumio volumio[925]: info: CoreCommandRouter::volumioPushQueue Jul 10 15:25:06 volumio volumio[925]: info: CorePlayQueue::saveQueue Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::updateTrackBlock Jul 10 15:25:06 volumio volumio[925]: info: CorePlayQueue::getTrackBlock Jul 10 15:25:06 volumio volumio[925]: info: CoreCommandRouter::volumioPlay Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::play index 0 Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::stop Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::play index undefined Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:25:06 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::startPlaybackTimer Jul 10 15:25:06 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:06 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:25:06 volumio volumio[925]: info: sendMpdCommand stop took 4 milliseconds Jul 10 15:25:06 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 15:25:06 volumio volumio[925]: info: Jul 10 15:25:06 volumio volumio[925]: ---------------------------- MPD announces system playlist update Jul 10 15:25:06 volumio volumio[925]: info: Ignoring MPD Status Update Jul 10 15:25:06 volumio volumio[925]: info: sendMpdCommand clear took 4 milliseconds Jul 10 15:25:06 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1720765479&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzIwNzY1NDc5fX19XX0_&Signature=L6FKTvMdcoQv2t7yD-HgvgZH3dv~DvSTuOMnjXRA9NNY7-ymHynuQ3sEtjeQe54CKdpMq1dF0sFwvCvcy-8AhMEi0aOhnCOXr-tVQYzSnr5annujSQ2MxvKm6dydU-nNXkugsPaeMr1GJ2hZSOEqiQ-KABx4tlty~6pfdSLDneh18xeExnpfpA3MKgEDE8bBu8Xhomcy7-xJQUyoMitsZrDeoyIKAy7a5UZ0iwSb70Qbb7WUVNcGeDoGxzhAnBpKP2sVXD-v9Fc~CDM5fPd2sgVg3vlwPCt~ISZn5EGeO4f03Nc3Q78l93EyDMyD8ge97nHcHmN9WBZ5h63AiQUjXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" Jul 10 15:25:06 volumio volumio[925]: error: updateQueue error: null Jul 10 15:25:06 volumio volumio[925]: info: Jul 10 15:25:06 volumio volumio[925]: ---------------------------- MPD announces system playlist update Jul 10 15:25:06 volumio volumio[925]: info: Ignoring MPD Status Update Jul 10 15:25:06 volumio volumio[925]: info: ------------------------------ 7ms Jul 10 15:25:06 volumio volumio[925]: info: sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1720765479&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzIwNzY1NDc5fX19XX0_&Signature=L6FKTvMdcoQv2t7yD-HgvgZH3dv~DvSTuOMnjXRA9NNY7-ymHynuQ3sEtjeQe54CKdpMq1dF0sFwvCvcy-8AhMEi0aOhnCOXr-tVQYzSnr5annujSQ2MxvKm6dydU-nNXkugsPaeMr1GJ2hZSOEqiQ-KABx4tlty~6pfdSLDneh18xeExnpfpA3MKgEDE8bBu8Xhomcy7-xJQUyoMitsZrDeoyIKAy7a5UZ0iwSb70Qbb7WUVNcGeDoGxzhAnBpKP2sVXD-v9Fc~CDM5fPd2sgVg3vlwPCt~ISZn5EGeO4f03Nc3Q78l93EyDMyD8ge97nHcHmN9WBZ5h63AiQUjXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" took 4 milliseconds Jul 10 15:25:06 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand play Jul 10 15:25:06 volumio volumio[925]: info: ------------------------------ 9ms Jul 10 15:25:06 volumio volumio[925]: info: sendMpdCommand play took 15 milliseconds Jul 10 15:25:06 volumio volumio[925]: info: ControllerMpd::getState Jul 10 15:25:06 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:25:06 volumio volumio[925]: info: Pushing Favourites {"uri":"webkbs/0","favourite":false} Jul 10 15:25:06 volumio volumio[925]: info: sendMpdCommand status took 16 milliseconds Jul 10 15:25:06 volumio volumio[925]: verbose: ControllerMpd::parseState Jul 10 15:25:06 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:25:06 volumio volumio[925]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 15:25:06 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:25:06 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:06 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:06 volumio volumio[925]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1fm_192_2.m3u8?Expires=1720765479&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzIwNzY1NDc5fX19XX0_&Signature=L6FKTvMdcoQv2t7yD-HgvgZH3dv~DvSTuOMnjXRA9NNY7-ymHynuQ3sEtjeQe54CKdpMq1dF0sFwvCvcy-8AhMEi0aOhnCOXr-tVQYzSnr5annujSQ2MxvKm6dydU-nNXkugsPaeMr1GJ2hZSOEqiQ-KABx4tlty~6pfdSLDneh18xeExnpfpA3MKgEDE8bBu8Xhomcy7-xJQUyoMitsZrDeoyIKAy7a5UZ0iwSb70Qbb7WUVNcGeDoGxzhAnBpKP2sVXD-v9Fc~CDM5fPd2sgVg3vlwPCt~ISZn5EGeO4f03Nc3Q78l93EyDMyD8ge97nHcHmN9WBZ5h63AiQUjXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1720765479&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzIwNzY1NDc5fX19XX0_&Signature=L6FKTvMdcoQv2t7yD-HgvgZH3dv~DvSTuOMnjXRA9NNY7-ymHynuQ3sEtjeQe54CKdpMq1dF0sFwvCvcy-8AhMEi0aOhnCOXr-tVQYzSnr5annujSQ2MxvKm6dydU-nNXkugsPaeMr1GJ2hZSOEqiQ-KABx4tlty~6pfdSLDneh18xeExnpfpA3MKgEDE8bBu8Xhomcy7-xJQUyoMitsZrDeoyIKAy7a5UZ0iwSb70Qbb7WUVNcGeDoGxzhAnBpKP2sVXD-v9Fc~CDM5fPd2sgVg3vlwPCt~ISZn5EGeO4f03Nc3Q78l93EyDMyD8ge97nHcHmN9WBZ5h63AiQUjXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Jul 10 15:25:06 volumio volumio[925]: verbose: CURRENT POSITION 0 Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::syncState stateService play Jul 10 15:25:06 volumio volumio[925]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:25:08 volumio volumio[925]: info: Jul 10 15:25:08 volumio volumio[925]: ---------------------------- MPD announces state update: player Jul 10 15:25:08 volumio volumio[925]: info: ControllerMpd::getState Jul 10 15:25:08 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:25:08 volumio volumio[925]: info: Jul 10 15:25:08 volumio volumio[925]: ---------------------------- MPD announces state update: player Jul 10 15:25:08 volumio volumio[925]: info: sendMpdCommand status took 6 milliseconds Jul 10 15:25:08 volumio volumio[925]: info: ControllerMpd::getState Jul 10 15:25:08 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:25:08 volumio volumio[925]: verbose: ControllerMpd::parseState Jul 10 15:25:08 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:25:08 volumio volumio[925]: info: sendMpdCommand status took 2 milliseconds Jul 10 15:25:08 volumio volumio[925]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:25:08 volumio volumio[925]: verbose: ControllerMpd::parseState Jul 10 15:25:08 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:25:08 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:25:08 volumio volumio[925]: info: ControllerMpd::pushState Jul 10 15:25:08 volumio volumio[925]: info: CoreCommandRouter::servicePushState Jul 10 15:25:08 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:08 volumio volumio[925]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Jul 10 15:25:08 volumio volumio[925]: info: ------------------------------ 18ms Jul 10 15:25:08 volumio volumio[925]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 10 15:25:08 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:25:08 volumio volumio[925]: info: ControllerMpd::pushState Jul 10 15:25:08 volumio volumio[925]: info: CoreCommandRouter::servicePushState Jul 10 15:25:08 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:08 volumio volumio[925]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Jul 10 15:25:08 volumio volumio[925]: info: ------------------------------ 15ms Jul 10 15:25:08 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:12 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:16 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:20 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:21 volumio volumio[925]: info: CoreCommandRouter::volumioPlay Jul 10 15:25:21 volumio volumio[925]: info: CoreStateMachine::play index undefined Jul 10 15:25:21 volumio volumio[925]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:25:21 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:23 volumio volumio[925]: info: Preload queue cleared Jul 10 15:25:23 volumio volumio[925]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::ClearQueue Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::stop Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::stPlaybackTimer Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::updateTrackBlock Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrackBlock Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:23 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:25:23 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::serviceStop Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:23 volumio volumio[925]: info: CoreCommandRouter::serviceStop Jul 10 15:25:23 volumio volumio[925]: info: ControllerMpd::stop Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::clearPlayQueue Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::saveQueue Jul 10 15:25:23 volumio volumio[925]: info: CoreCommandRouter::volumioPushQueue Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::addQueueItems Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::addQueueItems Jul 10 15:25:23 volumio volumio[925]: info: Preload queue cleared Jul 10 15:25:23 volumio volumio[925]: info: Adding Item to queue: webkbs/0 Jul 10 15:25:23 volumio volumio[925]: info: Using cached record of: webkbs/0 Jul 10 15:25:23 volumio volumio[925]: info: Adding Item to queue: webkbs/1 Jul 10 15:25:23 volumio volumio[925]: info: Using cached record of: webkbs/1 Jul 10 15:25:23 volumio volumio[925]: info: Adding Item to queue: webkbs/2 Jul 10 15:25:23 volumio volumio[925]: info: Using cached record of: webkbs/2 Jul 10 15:25:23 volumio volumio[925]: info: Adding Item to queue: webkbs/3 Jul 10 15:25:23 volumio volumio[925]: info: Using cached record of: webkbs/3 Jul 10 15:25:23 volumio volumio[925]: info: Adding Item to queue: webkbs/4 Jul 10 15:25:23 volumio volumio[925]: info: Using cached record of: webkbs/4 Jul 10 15:25:23 volumio volumio[925]: info: Adding Item to queue: webkbs/5 Jul 10 15:25:23 volumio volumio[925]: info: Using cached record of: webkbs/5 Jul 10 15:25:23 volumio volumio[925]: info: CoreCommandRouter::volumioPushQueue Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::saveQueue Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::updateTrackBlock Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrackBlock Jul 10 15:25:23 volumio volumio[925]: info: CoreCommandRouter::volumioPlay Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::play index 0 Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::stop Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::play index undefined Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::startPlaybackTimer Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:25:23 volumio volumio[925]: info: Jul 10 15:25:23 volumio volumio[925]: ---------------------------- MPD announces state update: player Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand stop took 40 milliseconds Jul 10 15:25:23 volumio volumio[925]: info: ControllerMpd::getState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand stop took 18 milliseconds Jul 10 15:25:23 volumio volumio[925]: info: ControllerMpd::getState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 15:25:23 volumio volumio[925]: info: Jul 10 15:25:23 volumio volumio[925]: ---------------------------- MPD announces system playlist update Jul 10 15:25:23 volumio volumio[925]: info: Ignoring MPD Status Update Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand status took 4 milliseconds Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand status took 3 milliseconds Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand clear took 3 milliseconds Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1720765479&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzIwNzY1NDc5fX19XX0_&Signature=L6FKTvMdcoQv2t7yD-HgvgZH3dv~DvSTuOMnjXRA9NNY7-ymHynuQ3sEtjeQe54CKdpMq1dF0sFwvCvcy-8AhMEi0aOhnCOXr-tVQYzSnr5annujSQ2MxvKm6dydU-nNXkugsPaeMr1GJ2hZSOEqiQ-KABx4tlty~6pfdSLDneh18xeExnpfpA3MKgEDE8bBu8Xhomcy7-xJQUyoMitsZrDeoyIKAy7a5UZ0iwSb70Qbb7WUVNcGeDoGxzhAnBpKP2sVXD-v9Fc~CDM5fPd2sgVg3vlwPCt~ISZn5EGeO4f03Nc3Q78l93EyDMyD8ge97nHcHmN9WBZ5h63AiQUjXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" Jul 10 15:25:23 volumio volumio[925]: error: updateQueue error: null Jul 10 15:25:23 volumio volumio[925]: info: Jul 10 15:25:23 volumio volumio[925]: ---------------------------- MPD announces system playlist update Jul 10 15:25:23 volumio volumio[925]: info: Ignoring MPD Status Update Jul 10 15:25:23 volumio volumio[925]: info: ------------------------------ 6ms Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1720765479&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzIwNzY1NDc5fX19XX0_&Signature=L6FKTvMdcoQv2t7yD-HgvgZH3dv~DvSTuOMnjXRA9NNY7-ymHynuQ3sEtjeQe54CKdpMq1dF0sFwvCvcy-8AhMEi0aOhnCOXr-tVQYzSnr5annujSQ2MxvKm6dydU-nNXkugsPaeMr1GJ2hZSOEqiQ-KABx4tlty~6pfdSLDneh18xeExnpfpA3MKgEDE8bBu8Xhomcy7-xJQUyoMitsZrDeoyIKAy7a5UZ0iwSb70Qbb7WUVNcGeDoGxzhAnBpKP2sVXD-v9Fc~CDM5fPd2sgVg3vlwPCt~ISZn5EGeO4f03Nc3Q78l93EyDMyD8ge97nHcHmN9WBZ5h63AiQUjXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" took 4 milliseconds Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand play Jul 10 15:25:23 volumio volumio[925]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 10 15:25:23 volumio volumio[925]: info: ------------------------------ 17ms Jul 10 15:25:23 volumio volumio[925]: info: ------------------------------ 10ms Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand play took 5 milliseconds Jul 10 15:25:23 volumio volumio[925]: info: ControllerMpd::getState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand status took 3 milliseconds Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:25:23 volumio volumio[925]: info: Pushing Favourites {"uri":"webkbs/0","favourite":false} Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:23 volumio volumio[925]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1fm_192_2.m3u8?Expires=1720765479&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzIwNzY1NDc5fX19XX0_&Signature=L6FKTvMdcoQv2t7yD-HgvgZH3dv~DvSTuOMnjXRA9NNY7-ymHynuQ3sEtjeQe54CKdpMq1dF0sFwvCvcy-8AhMEi0aOhnCOXr-tVQYzSnr5annujSQ2MxvKm6dydU-nNXkugsPaeMr1GJ2hZSOEqiQ-KABx4tlty~6pfdSLDneh18xeExnpfpA3MKgEDE8bBu8Xhomcy7-xJQUyoMitsZrDeoyIKAy7a5UZ0iwSb70Qbb7WUVNcGeDoGxzhAnBpKP2sVXD-v9Fc~CDM5fPd2sgVg3vlwPCt~ISZn5EGeO4f03Nc3Q78l93EyDMyD8ge97nHcHmN9WBZ5h63AiQUjXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1720765479&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzIwNzY1NDc5fX19XX0_&Signature=L6FKTvMdcoQv2t7yD-HgvgZH3dv~DvSTuOMnjXRA9NNY7-ymHynuQ3sEtjeQe54CKdpMq1dF0sFwvCvcy-8AhMEi0aOhnCOXr-tVQYzSnr5annujSQ2MxvKm6dydU-nNXkugsPaeMr1GJ2hZSOEqiQ-KABx4tlty~6pfdSLDneh18xeExnpfpA3MKgEDE8bBu8Xhomcy7-xJQUyoMitsZrDeoyIKAy7a5UZ0iwSb70Qbb7WUVNcGeDoGxzhAnBpKP2sVXD-v9Fc~CDM5fPd2sgVg3vlwPCt~ISZn5EGeO4f03Nc3Q78l93EyDMyD8ge97nHcHmN9WBZ5h63AiQUjXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Jul 10 15:25:23 volumio volumio[925]: verbose: CURRENT POSITION 0 Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::syncState stateService play Jul 10 15:25:23 volumio volumio[925]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:25:23 volumio volumio[925]: info: Jul 10 15:25:23 volumio volumio[925]: ---------------------------- MPD announces state update: player Jul 10 15:25:23 volumio volumio[925]: info: ControllerMpd::getState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand status took 2 milliseconds Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:25:23 volumio volumio[925]: info: Jul 10 15:25:23 volumio volumio[925]: ---------------------------- MPD announces state update: player Jul 10 15:25:23 volumio volumio[925]: info: ControllerMpd::getState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:25:23 volumio volumio[925]: info: ControllerMpd::pushState Jul 10 15:25:23 volumio volumio[925]: info: CoreCommandRouter::servicePushState Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:23 volumio volumio[925]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Jul 10 15:25:23 volumio volumio[925]: info: ------------------------------ 10ms Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand status took 4 milliseconds Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseState Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:25:23 volumio volumio[925]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 10 15:25:23 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:25:23 volumio volumio[925]: info: ControllerMpd::pushState Jul 10 15:25:23 volumio volumio[925]: info: CoreCommandRouter::servicePushState Jul 10 15:25:23 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:23 volumio volumio[925]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Jul 10 15:25:23 volumio volumio[925]: info: ------------------------------ 17ms Jul 10 15:25:24 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:28 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:32 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:33 volumio volumio[925]: info: VolumeController::SetAlsaVolume46 Jul 10 15:25:33 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:25:33 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:33 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:25:33 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:25:33 volumio volumio[925]: info: VolumeController::SetAlsaVolume42 Jul 10 15:25:33 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:25:33 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:33 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:25:33 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:25:36 volumio volumio[925]: info: VolumeController::SetAlsaVolume51 Jul 10 15:25:36 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:25:36 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:25:36 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:25:36 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:25:36 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:40 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:44 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:48 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:52 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:25:56 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:26:00 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:26:03 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Jul 10 15:26:03 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:26:03 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:26:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:26:03 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:26:03 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Jul 10 15:26:03 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:26:03 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:26:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:26:03 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:26:03 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Jul 10 15:26:03 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:26:03 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:26:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:26:03 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:26:04 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Jul 10 15:26:04 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:26:04 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:26:04 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:26:04 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:26:04 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Jul 10 15:26:04 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:26:04 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:26:04 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:26:04 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:26:04 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:26:05 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Jul 10 15:26:05 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:26:05 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:26:05 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:26:05 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:26:05 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Jul 10 15:26:05 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:26:05 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:26:05 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:26:05 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:26:06 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Jul 10 15:26:06 volumio volumio[925]: info: CoreStateMachine::pushState Jul 10 15:26:06 volumio volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 15:26:06 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:26:06 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 15:26:08 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:26:12 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:26:16 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:26:20 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:26:24 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:26:28 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 15:26:29 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 10 15:26:29 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 10 15:26:30 volumio volumio[925]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 15:26:30 volumio volumio[925]: Error: getaddrinfo -3007 Jul 10 15:26:30 volumio volumio[925]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jul 10 15:26:30 volumio volumio[925]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jul 10 15:26:30 volumio volumio[925]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jul 10 15:26:30 volumio volumio[925]: code: -3007, Jul 10 15:26:30 volumio volumio[925]: errno: -3007, Jul 10 15:26:30 volumio volumio[925]: syscall: 'getaddrinfo' Jul 10 15:26:30 volumio volumio[925]: } Jul 10 15:26:30 volumio volumio[925]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 15:26:30 volumio sudo[2874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 15:25 Jul 10 15:26:30 volumio sudo[2874]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"