Dec 26 01:04:00 volumio3 volumio[3425]: info: Setting Geolocation for MyVolumio to as2 Dec 26 01:04:00 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:00 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:00 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:00 volumio3 volumio[3425]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 01:04:01 volumio3 volumio[3425]: info: Updating MyVolumio device info Dec 26 01:04:01 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:01 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:01 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:01 volumio3 volumio[3425]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 01:04:03 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 01:04:03 volumio3 volumio[3425]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 26 01:04:03 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:04:03 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:04 volumio3 volumio[3425]: info: MYVOLUMIO: Adding device Dec 26 01:04:04 volumio3 volumio[3425]: info: MYVOLUMIO: Evaluating Server Dec 26 01:04:07 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPlay Dec 26 01:04:07 volumio3 volumio[3425]: info: CoreStateMachine::play index undefined Dec 26 01:04:07 volumio3 volumio[3425]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 01:04:07 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:07 volumio3 volumio[3425]: info: CoreStateMachine::startPlaybackTimer Dec 26 01:04:07 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:07 volumio3 volumio[3425]: info: [1766678647260] ControllerWebradio::clearAddPlayTrack Dec 26 01:04:07 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 01:04:07 volumio3 volumio[3425]: info: sendMpdCommand stop took 3 milliseconds Dec 26 01:04:07 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 01:04:07 volumio3 volumio[3425]: info: sendMpdCommand clear took 1 milliseconds Dec 26 01:04:07 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs2fm.pls" Dec 26 01:04:07 volumio3 volumio[3425]: info: Dec 26 01:04:07 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:07 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:07 volumio3 volumio[3425]: info: Dec 26 01:04:07 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:07 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:07 volumio3 volumio[3425]: info: Dec 26 01:04:07 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:07 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:07 volumio3 volumio[3425]: info: Setting Geolocation for MyVolumio to as2 Dec 26 01:04:07 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:07 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:07 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:08 volumio3 volumio[3425]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetBrowseSources Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 01:04:08 volumio3 volumio[3425]: info: Updating MyVolumio device info Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 01:04:08 volumio3 volumio[3425]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 01:04:12 volumio3 volumio[3425]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs2fm.pls" took 5204 milliseconds Dec 26 01:04:12 volumio3 volumio[3425]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 01:04:12 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand play Dec 26 01:04:12 volumio3 volumio[3425]: info: Dec 26 01:04:12 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:12 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:12 volumio3 volumio[3425]: info: ------------------------------ 5209ms Dec 26 01:04:12 volumio3 volumio[3425]: info: ------------------------------ 5205ms Dec 26 01:04:12 volumio3 volumio[3425]: info: ------------------------------ 5205ms Dec 26 01:04:12 volumio3 volumio[3425]: info: Dec 26 01:04:12 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:12 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:12 volumio3 volumio[3425]: info: Dec 26 01:04:12 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:12 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:12 volumio3 volumio[3425]: info: sendMpdCommand play took 11 milliseconds Dec 26 01:04:12 volumio3 volumio[3425]: info: ------------------------------ 10ms Dec 26 01:04:12 volumio3 volumio[3425]: info: ------------------------------ 5ms Dec 26 01:04:12 volumio3 volumio[3425]: info: ------------------------------ 4ms Dec 26 01:04:12 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPlay Dec 26 01:04:12 volumio3 volumio[3425]: info: CoreStateMachine::play index undefined Dec 26 01:04:12 volumio3 volumio[3425]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 01:04:12 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:12 volumio3 volumio[3425]: info: CoreStateMachine::startPlaybackTimer Dec 26 01:04:12 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:12 volumio3 volumio[3425]: info: [1766678652645] ControllerWebradio::clearAddPlayTrack Dec 26 01:04:12 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 01:04:13 volumio3 volumio[3425]: info: sendMpdCommand stop took 889 milliseconds Dec 26 01:04:13 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 01:04:13 volumio3 volumio[3425]: info: Dec 26 01:04:13 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:13 volumio3 volumio[3425]: info: Dec 26 01:04:13 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:13 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:13 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:13 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:13 volumio3 volumio[3425]: info: sendMpdCommand clear took 3 milliseconds Dec 26 01:04:13 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs2fm.pls" Dec 26 01:04:13 volumio3 volumio[3425]: info: Dec 26 01:04:13 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:13 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:13 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:13 volumio3 volumio[3425]: info: Dec 26 01:04:13 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:13 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:13 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:13 volumio3 volumio[3425]: error: updateQueue error: null Dec 26 01:04:13 volumio3 volumio[3425]: info: sendMpdCommand status took 11 milliseconds Dec 26 01:04:13 volumio3 volumio[3425]: info: ------------------------------ 13ms Dec 26 01:04:13 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:13 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:13 volumio3 volumio[3425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 01:04:13 volumio3 volumio[3425]: info: ------------------------------ 58ms Dec 26 01:04:13 volumio3 volumio[3425]: info: Dec 26 01:04:13 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:13 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:13 volumio3 volumio[3425]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/kbs2fm.pls" took 58 milliseconds Dec 26 01:04:13 volumio3 volumio[3425]: info: sendMpdCommand status took 57 milliseconds Dec 26 01:04:13 volumio3 volumio[3425]: info: sendMpdCommand status took 55 milliseconds Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 01:04:13 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand play Dec 26 01:04:13 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:13 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:13 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:13 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 26 01:04:13 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService stop Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus stop Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:13 volumio3 volumio[3425]: info: No code Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:13 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:13 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 26 01:04:13 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService stop Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus stop Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:13 volumio3 volumio[3425]: info: No code Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:13 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:13 volumio3 volumio[3425]: info: ------------------------------ 236ms Dec 26 01:04:13 volumio3 volumio[3425]: info: ------------------------------ 236ms Dec 26 01:04:13 volumio3 volumio[3425]: info: Dec 26 01:04:13 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:13 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:13 volumio3 volumio[3425]: info: Dec 26 01:04:13 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:13 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:13 volumio3 volumio[3425]: info: ------------------------------ 267ms Dec 26 01:04:13 volumio3 volumio[3425]: info: sendMpdCommand play took 261 milliseconds Dec 26 01:04:13 volumio3 volumio[3425]: info: ------------------------------ 58ms Dec 26 01:04:13 volumio3 volumio[3425]: info: ------------------------------ 57ms Dec 26 01:04:15 volumio3 volumio[3425]: info: Dec 26 01:04:15 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:15 volumio3 volumio[3425]: info: Dec 26 01:04:15 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:15 volumio3 volumio[3425]: info: Dec 26 01:04:15 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:15 volumio3 volumio[3425]: info: sendMpdCommand status took 22 milliseconds Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:15 volumio3 volumio[3425]: info: Dec 26 01:04:15 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:15 volumio3 volumio[3425]: info: Dec 26 01:04:15 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:15 volumio3 volumio[3425]: info: sendMpdCommand status took 27 milliseconds Dec 26 01:04:15 volumio3 volumio[3425]: info: sendMpdCommand status took 24 milliseconds Dec 26 01:04:15 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 26 01:04:15 volumio3 volumio[3425]: info: sendMpdCommand status took 4 milliseconds Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:15 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"KBS Cool FM","artist":null,"album":null,"uri":"https://2fm-ad.gscdn.kbs.co.kr/2fm_ad_192_1.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8yZm0tYWQuZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExMzJ9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=h9Zlp-V~6IJ9KkhC0o8bj-2M4j3Cku4aNZ43ZEo0w30IIn5dBTKWbkqDs~Y2QvoBDFB3dMo6E9eJC1cYKKaxa0wC-oH5IzvSsnaEN3EdywpZz0T2sRoJxTzCRYEb8FXIWabNT6DPuQgCmxOQg0OEWwzlc4DZSsLabpQQhbtO3ODnGseqlbf-lBB7ZM8zrAzwp3mTGYOYLsRcg2qzR5AFFR0KZlPK9VK1KCyLgo91A2wmXZKhCwOQ1KpoqYSzvTsL7lNrgssxyKD8QpagLJPkm0UviraR6Uc0Qh-1mZfP-5fHEok40Dv5NvW9mNCSNDj46EkE-fBHxBnp0eoVj3yE5w__","trackType":""} Dec 26 01:04:15 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus stop Dec 26 01:04:15 volumio3 volumio[3425]: info: ------------------------------ 43ms Dec 26 01:04:15 volumio3 volumio[3425]: info: Dec 26 01:04:15 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:15 volumio3 volumio[3425]: info: sendMpdCommand status took 15 milliseconds Dec 26 01:04:15 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 26 01:04:15 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 26 01:04:15 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:15 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:15 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":484,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"KBS Cool FM","artist":null,"album":null,"uri":"https://2fm-ad.gscdn.kbs.co.kr/2fm_ad_192_1.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8yZm0tYWQuZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExMzJ9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=h9Zlp-V~6IJ9KkhC0o8bj-2M4j3Cku4aNZ43ZEo0w30IIn5dBTKWbkqDs~Y2QvoBDFB3dMo6E9eJC1cYKKaxa0wC-oH5IzvSsnaEN3EdywpZz0T2sRoJxTzCRYEb8FXIWabNT6DPuQgCmxOQg0OEWwzlc4DZSsLabpQQhbtO3ODnGseqlbf-lBB7ZM8zrAzwp3mTGYOYLsRcg2qzR5AFFR0KZlPK9VK1KCyLgo91A2wmXZKhCwOQ1KpoqYSzvTsL7lNrgssxyKD8QpagLJPkm0UviraR6Uc0Qh-1mZfP-5fHEok40Dv5NvW9mNCSNDj46EkE-fBHxBnp0eoVj3yE5w__","trackType":""} Dec 26 01:04:15 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:15 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:15 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":610,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"KBS Cool FM","artist":null,"album":null,"uri":"https://2fm-ad.gscdn.kbs.co.kr/2fm_ad_192_1.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8yZm0tYWQuZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExMzJ9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=h9Zlp-V~6IJ9KkhC0o8bj-2M4j3Cku4aNZ43ZEo0w30IIn5dBTKWbkqDs~Y2QvoBDFB3dMo6E9eJC1cYKKaxa0wC-oH5IzvSsnaEN3EdywpZz0T2sRoJxTzCRYEb8FXIWabNT6DPuQgCmxOQg0OEWwzlc4DZSsLabpQQhbtO3ODnGseqlbf-lBB7ZM8zrAzwp3mTGYOYLsRcg2qzR5AFFR0KZlPK9VK1KCyLgo91A2wmXZKhCwOQ1KpoqYSzvTsL7lNrgssxyKD8QpagLJPkm0UviraR6Uc0Qh-1mZfP-5fHEok40Dv5NvW9mNCSNDj46EkE-fBHxBnp0eoVj3yE5w__","trackType":""} Dec 26 01:04:15 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:15 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:15 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:15 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"KBS Cool FM","artist":null,"album":null,"uri":"https://2fm-ad.gscdn.kbs.co.kr/2fm_ad_192_1.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8yZm0tYWQuZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExMzJ9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=h9Zlp-V~6IJ9KkhC0o8bj-2M4j3Cku4aNZ43ZEo0w30IIn5dBTKWbkqDs~Y2QvoBDFB3dMo6E9eJC1cYKKaxa0wC-oH5IzvSsnaEN3EdywpZz0T2sRoJxTzCRYEb8FXIWabNT6DPuQgCmxOQg0OEWwzlc4DZSsLabpQQhbtO3ODnGseqlbf-lBB7ZM8zrAzwp3mTGYOYLsRcg2qzR5AFFR0KZlPK9VK1KCyLgo91A2wmXZKhCwOQ1KpoqYSzvTsL7lNrgssxyKD8QpagLJPkm0UviraR6Uc0Qh-1mZfP-5fHEok40Dv5NvW9mNCSNDj46EkE-fBHxBnp0eoVj3yE5w__","trackType":""} Dec 26 01:04:15 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:15 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:15 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:16 volumio3 volumio[3425]: info: ------------------------------ 250ms Dec 26 01:04:16 volumio3 volumio[3425]: info: ------------------------------ 249ms Dec 26 01:04:16 volumio3 volumio[3425]: info: ------------------------------ 230ms Dec 26 01:04:16 volumio3 volumio[3425]: info: sendMpdCommand status took 217 milliseconds Dec 26 01:04:16 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 213 milliseconds Dec 26 01:04:16 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:16 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:16 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:16 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"KBS Cool FM","artist":null,"album":null,"uri":"https://2fm-ad.gscdn.kbs.co.kr/2fm_ad_192_1.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8yZm0tYWQuZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExMzJ9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=h9Zlp-V~6IJ9KkhC0o8bj-2M4j3Cku4aNZ43ZEo0w30IIn5dBTKWbkqDs~Y2QvoBDFB3dMo6E9eJC1cYKKaxa0wC-oH5IzvSsnaEN3EdywpZz0T2sRoJxTzCRYEb8FXIWabNT6DPuQgCmxOQg0OEWwzlc4DZSsLabpQQhbtO3ODnGseqlbf-lBB7ZM8zrAzwp3mTGYOYLsRcg2qzR5AFFR0KZlPK9VK1KCyLgo91A2wmXZKhCwOQ1KpoqYSzvTsL7lNrgssxyKD8QpagLJPkm0UviraR6Uc0Qh-1mZfP-5fHEok40Dv5NvW9mNCSNDj46EkE-fBHxBnp0eoVj3yE5w__","trackType":""} Dec 26 01:04:16 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:16 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:16 volumio3 volumio[3425]: info: ------------------------------ 312ms Dec 26 01:04:16 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 268 milliseconds Dec 26 01:04:16 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:16 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"191 Kbps","isStreaming":false,"title":"KBS Cool FM","artist":null,"album":null,"uri":"https://2fm-ad.gscdn.kbs.co.kr/2fm_ad_192_1.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8yZm0tYWQuZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExMzJ9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=h9Zlp-V~6IJ9KkhC0o8bj-2M4j3Cku4aNZ43ZEo0w30IIn5dBTKWbkqDs~Y2QvoBDFB3dMo6E9eJC1cYKKaxa0wC-oH5IzvSsnaEN3EdywpZz0T2sRoJxTzCRYEb8FXIWabNT6DPuQgCmxOQg0OEWwzlc4DZSsLabpQQhbtO3ODnGseqlbf-lBB7ZM8zrAzwp3mTGYOYLsRcg2qzR5AFFR0KZlPK9VK1KCyLgo91A2wmXZKhCwOQ1KpoqYSzvTsL7lNrgssxyKD8QpagLJPkm0UviraR6Uc0Qh-1mZfP-5fHEok40Dv5NvW9mNCSNDj46EkE-fBHxBnp0eoVj3yE5w__","trackType":""} Dec 26 01:04:16 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:16 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:16 volumio3 volumio[3425]: info: ------------------------------ 540ms Dec 26 01:04:16 volumio3 volumio[3425]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 01:04:16 volumio3 volumio[3425]: info: Received Get System Info Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 01:04:16 volumio3 volumio[3425]: info: Discovery: Getting this device information Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 01:04:16 volumio3 volumio[3425]: info: Received Get System Info Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 01:04:16 volumio3 volumio[3425]: info: Discovery: Getting this device information Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 01:04:16 volumio3 volumio[3425]: info: Received Get System Info Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 01:04:16 volumio3 volumio[3425]: info: Discovery: Getting this device information Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 01:04:16 volumio3 volumio[3425]: info: Received Get System Info Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 01:04:16 volumio3 volumio[3425]: info: Discovery: Getting this device information Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 01:04:16 volumio3 volumio[3425]: info: Received Get System Info Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 01:04:16 volumio3 volumio[3425]: info: Discovery: Getting this device information Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 01:04:16 volumio3 volumio[3425]: info: Received Get System Info Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 01:04:16 volumio3 volumio[3425]: info: Discovery: Getting this device information Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 01:04:16 volumio3 volumio[3425]: info: Received Get System Info Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 01:04:16 volumio3 volumio[3425]: info: Discovery: Getting this device information Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:04:16 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 01:04:25 volumio3 systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Dec 26 01:04:25 volumio3 systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Dec 26 01:04:25 volumio3 systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Dec 26 01:04:25 volumio3 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Dec 26 01:04:28 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 26 01:04:28 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 01:04:29 volumio3 volumio[3425]: info: Preload queue cleared Dec 26 01:04:34 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 26 01:04:34 volumio3 volumio[3425]: info: Preload queue cleared Dec 26 01:04:34 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 01:04:34 volumio3 volumio[3425]: info: Received Get System Info Dec 26 01:04:34 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 01:04:34 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 01:04:34 volumio3 volumio[3425]: info: Discovery: Getting this device information Dec 26 01:04:34 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:04:34 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 01:04:42 volumio3 volumio[3425]: info: Preload queue cleared Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::ClearQueue Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::stop Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::stPlaybackTimer Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::updateTrackBlock Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrackBlock Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::serviceStop Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::serviceStop Dec 26 01:04:42 volumio3 volumio[3425]: info: [1766678682741] ControllerWebradio::stop Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::clearPlayQueue Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::saveQueue Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushQueue Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::addQueueItems Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::addQueueItems Dec 26 01:04:42 volumio3 volumio[3425]: info: Preload queue cleared Dec 26 01:04:42 volumio3 volumio[3425]: info: Adding Item to queue: http://serpent0.duckdns.org:8088/kbsfm.pls Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushQueue Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::saveQueue Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::updateTrackBlock Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrackBlock Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPlay Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::play index 0 Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::stop Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::play index undefined Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::startPlaybackTimer Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:42 volumio3 volumio[3425]: info: [1766678682799] ControllerWebradio::clearAddPlayTrack Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 01:04:42 volumio3 volumio[3425]: info: Dec 26 01:04:42 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:42 volumio3 volumio[3425]: info: sendMpdCommand stop took 104 milliseconds Dec 26 01:04:42 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:42 volumio3 volumio[3425]: info: sendMpdCommand stop took 47 milliseconds Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 01:04:42 volumio3 volumio[3425]: info: Dec 26 01:04:42 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:42 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:42 volumio3 volumio[3425]: info: Dec 26 01:04:42 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:42 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:42 volumio3 volumio[3425]: info: Dec 26 01:04:42 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:42 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:42 volumio3 volumio[3425]: info: sendMpdCommand status took 10 milliseconds Dec 26 01:04:42 volumio3 volumio[3425]: info: sendMpdCommand clear took 10 milliseconds Dec 26 01:04:42 volumio3 volumio[3425]: info: sendMpdCommand status took 7 milliseconds Dec 26 01:04:42 volumio3 volumio[3425]: info: sendMpdCommand status took 5 milliseconds Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:42 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:42 volumio3 volumio[3425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 01:04:42 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:42 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:42 volumio3 volumio[3425]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 26 01:04:42 volumio3 volumio[3425]: info: ------------------------------ 73ms Dec 26 01:04:42 volumio3 volumio[3425]: info: ------------------------------ 71ms Dec 26 01:04:42 volumio3 volumio[3425]: info: Dec 26 01:04:42 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:42 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:42 volumio3 volumio[3425]: info: Dec 26 01:04:42 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:42 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:42 volumio3 volumio[3425]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 26 01:04:42 volumio3 volumio[3425]: error: updateQueue error: null Dec 26 01:04:42 volumio3 volumio[3425]: info: Dec 26 01:04:42 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:42 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:42 volumio3 volumio[3425]: info: ------------------------------ 81ms Dec 26 01:04:42 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 77 milliseconds Dec 26 01:04:42 volumio3 volumio[3425]: info: sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" took 76 milliseconds Dec 26 01:04:42 volumio3 volumio[3425]: info: ------------------------------ 13ms Dec 26 01:04:42 volumio3 volumio[3425]: info: ------------------------------ 11ms Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:42 volumio3 volumio[3425]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 01:04:42 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand play Dec 26 01:04:42 volumio3 volumio[3425]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 26 01:04:42 volumio3 volumio[3425]: info: ------------------------------ 98ms Dec 26 01:04:42 volumio3 volumio[3425]: info: Dec 26 01:04:42 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:42 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:42 volumio3 volumio[3425]: info: Dec 26 01:04:42 volumio3 volumio[3425]: ---------------------------- MPD announces system playlist update Dec 26 01:04:42 volumio3 volumio[3425]: info: Ignoring MPD Status Update Dec 26 01:04:42 volumio3 volumio[3425]: info: ------------------------------ 34ms Dec 26 01:04:42 volumio3 volumio[3425]: info: sendMpdCommand play took 29 milliseconds Dec 26 01:04:42 volumio3 volumio[3425]: info: ------------------------------ 27ms Dec 26 01:04:42 volumio3 volumio[3425]: info: ------------------------------ 26ms Dec 26 01:04:44 volumio3 volumio[3425]: info: Dec 26 01:04:44 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:44 volumio3 volumio[3425]: info: Dec 26 01:04:44 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:44 volumio3 volumio[3425]: info: Dec 26 01:04:44 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:44 volumio3 volumio[3425]: info: Dec 26 01:04:44 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand status took 9 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand status took 8 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:44 volumio3 volumio[3425]: info: Dec 26 01:04:44 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:44 volumio3 volumio[3425]: info: Dec 26 01:04:44 volumio3 volumio[3425]: ---------------------------- MPD announces state update: player Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::getState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand status Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand status took 18 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand status took 17 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand status took 9 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand status took 8 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseState Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:44 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"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=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExNzd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=VyZtAV0yPy6uhRIMIr42ey9sR13SJVxmzDhPM3Ojn5A-q7j~Tt2PYb~0jOat8mRzCtoBi4Pnx5xMuEC9TOyyJHM1EBtG2oFQF0I3XXw2ul7gdjWQG58rN~z2QYNzNcct5hcpeaqd7N6wgWNHzKBupY6Y9CmfACwBT5pLT-QxFDuPFaTtVZ3V5n2BETzfrmoXSXenS0zCl2EIWcRA6rvPiWGt~URy2L7ChuZxAZgaMRxVjqMMzb2ZpWFjKYnlTN28O8TbTs7BmJGghGVHM5PPn~jrHewQWVLiq3vt-gnUJgOk-OdcA~wNFk8tB7Mi4kYJw75RCIQ18nZEBm837m08kw__","trackType":""} Dec 26 01:04:44 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus stop Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:44 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"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=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExNzd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=VyZtAV0yPy6uhRIMIr42ey9sR13SJVxmzDhPM3Ojn5A-q7j~Tt2PYb~0jOat8mRzCtoBi4Pnx5xMuEC9TOyyJHM1EBtG2oFQF0I3XXw2ul7gdjWQG58rN~z2QYNzNcct5hcpeaqd7N6wgWNHzKBupY6Y9CmfACwBT5pLT-QxFDuPFaTtVZ3V5n2BETzfrmoXSXenS0zCl2EIWcRA6rvPiWGt~URy2L7ChuZxAZgaMRxVjqMMzb2ZpWFjKYnlTN28O8TbTs7BmJGghGVHM5PPn~jrHewQWVLiq3vt-gnUJgOk-OdcA~wNFk8tB7Mi4kYJw75RCIQ18nZEBm837m08kw__","trackType":""} Dec 26 01:04:44 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:44 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: ------------------------------ 67ms Dec 26 01:04:44 volumio3 volumio[3425]: info: ------------------------------ 102ms Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 69 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 69 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 67 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: info: sendMpdCommand playlistinfo took 67 milliseconds Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:44 volumio3 volumio[3425]: verbose: ControllerMpd::parseTrackInfo Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:44 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"195 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExNzd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=VyZtAV0yPy6uhRIMIr42ey9sR13SJVxmzDhPM3Ojn5A-q7j~Tt2PYb~0jOat8mRzCtoBi4Pnx5xMuEC9TOyyJHM1EBtG2oFQF0I3XXw2ul7gdjWQG58rN~z2QYNzNcct5hcpeaqd7N6wgWNHzKBupY6Y9CmfACwBT5pLT-QxFDuPFaTtVZ3V5n2BETzfrmoXSXenS0zCl2EIWcRA6rvPiWGt~URy2L7ChuZxAZgaMRxVjqMMzb2ZpWFjKYnlTN28O8TbTs7BmJGghGVHM5PPn~jrHewQWVLiq3vt-gnUJgOk-OdcA~wNFk8tB7Mi4kYJw75RCIQ18nZEBm837m08kw__","trackType":""} Dec 26 01:04:44 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:44 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:44 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"195 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExNzd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=VyZtAV0yPy6uhRIMIr42ey9sR13SJVxmzDhPM3Ojn5A-q7j~Tt2PYb~0jOat8mRzCtoBi4Pnx5xMuEC9TOyyJHM1EBtG2oFQF0I3XXw2ul7gdjWQG58rN~z2QYNzNcct5hcpeaqd7N6wgWNHzKBupY6Y9CmfACwBT5pLT-QxFDuPFaTtVZ3V5n2BETzfrmoXSXenS0zCl2EIWcRA6rvPiWGt~URy2L7ChuZxAZgaMRxVjqMMzb2ZpWFjKYnlTN28O8TbTs7BmJGghGVHM5PPn~jrHewQWVLiq3vt-gnUJgOk-OdcA~wNFk8tB7Mi4kYJw75RCIQ18nZEBm837m08kw__","trackType":""} Dec 26 01:04:44 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:44 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:44 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"195 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExNzd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=VyZtAV0yPy6uhRIMIr42ey9sR13SJVxmzDhPM3Ojn5A-q7j~Tt2PYb~0jOat8mRzCtoBi4Pnx5xMuEC9TOyyJHM1EBtG2oFQF0I3XXw2ul7gdjWQG58rN~z2QYNzNcct5hcpeaqd7N6wgWNHzKBupY6Y9CmfACwBT5pLT-QxFDuPFaTtVZ3V5n2BETzfrmoXSXenS0zCl2EIWcRA6rvPiWGt~URy2L7ChuZxAZgaMRxVjqMMzb2ZpWFjKYnlTN28O8TbTs7BmJGghGVHM5PPn~jrHewQWVLiq3vt-gnUJgOk-OdcA~wNFk8tB7Mi4kYJw75RCIQ18nZEBm837m08kw__","trackType":""} Dec 26 01:04:44 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:44 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: ControllerMpd::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::servicePushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CorePlayQueue::getTrack 0 Dec 26 01:04:44 volumio3 volumio[3425]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"195 Kbps","isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NjY4NTExNzd9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=VyZtAV0yPy6uhRIMIr42ey9sR13SJVxmzDhPM3Ojn5A-q7j~Tt2PYb~0jOat8mRzCtoBi4Pnx5xMuEC9TOyyJHM1EBtG2oFQF0I3XXw2ul7gdjWQG58rN~z2QYNzNcct5hcpeaqd7N6wgWNHzKBupY6Y9CmfACwBT5pLT-QxFDuPFaTtVZ3V5n2BETzfrmoXSXenS0zCl2EIWcRA6rvPiWGt~URy2L7ChuZxAZgaMRxVjqMMzb2ZpWFjKYnlTN28O8TbTs7BmJGghGVHM5PPn~jrHewQWVLiq3vt-gnUJgOk-OdcA~wNFk8tB7Mi4kYJw75RCIQ18nZEBm837m08kw__","trackType":""} Dec 26 01:04:44 volumio3 volumio[3425]: verbose: CURRENT POSITION 0 Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState stateService play Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::syncState currentStatus play Dec 26 01:04:44 volumio3 volumio[3425]: info: Received an update from plugin. extracting info from payload Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreStateMachine::pushState Dec 26 01:04:44 volumio3 volumio[3425]: info: CoreCommandRouter::volumioPushState Dec 26 01:04:44 volumio3 volumio[3425]: info: ------------------------------ 320ms Dec 26 01:04:44 volumio3 volumio[3425]: info: ------------------------------ 321ms Dec 26 01:04:44 volumio3 volumio[3425]: info: ------------------------------ 310ms Dec 26 01:04:44 volumio3 volumio[3425]: info: ------------------------------ 309ms Dec 26 01:04:44 volumio3 volumio[3425]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 26 01:04:49 volumio3 volumio[3425]: info: CoreCommandRouter::volumioGetState Dec 26 01:05:08 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Dec 26 01:05:08 volumio3 volumio[3425]: info: Preload queue cleared Dec 26 01:05:10 volumio3 volumio[3425]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Dec 26 01:05:10 volumio3 volumio[3425]: info: Preload queue cleared Dec 26 01:05:10 volumio3 volumio[3425]: info: Preloading song: webkbs/0 Dec 26 01:05:10 volumio3 volumio[3425]: info: Preloading song: webkbs/1 Dec 26 01:05:10 volumio3 volumio[3425]: info: Preloading song: webkbs/2 Dec 26 01:05:10 volumio3 volumio[3425]: info: Preloading song: webkbs/3 Dec 26 01:05:10 volumio3 volumio[3425]: info: Preloading song: webkbs/4 Dec 26 01:05:10 volumio3 volumio[3425]: info: Preloading song: webkbs/5 Dec 26 01:05:11 volumio3 volumio[3425]: info: Exploding uri webkbs/0 in service personal_radio Dec 26 01:05:11 volumio3 volumio[3425]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 01:05:11 volumio3 volumio[3425]: TypeError [ERR_INVALID_ARG_TYPE]: The "url" argument must be of type string. Received undefined Dec 26 01:05:11 volumio3 volumio[3425]: at new NodeError (node:internal/errors:405:5) Dec 26 01:05:11 volumio3 volumio[3425]: at validateString (node:internal/validators:162:11) Dec 26 01:05:11 volumio3 volumio[3425]: at Url.parse (node:url:170:3) Dec 26 01:05:11 volumio3 volumio[3425]: at Object.urlParse [as parse] (node:url:141:13) Dec 26 01:05:11 volumio3 volumio[3425]: at /data/plugins/music_service/personal_radio/index.js:675:19 Dec 26 01:05:11 volumio3 volumio[3425]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Dec 26 01:05:11 volumio3 volumio[3425]: code: 'ERR_INVALID_ARG_TYPE' Dec 26 01:05:11 volumio3 volumio[3425]: } Dec 26 01:05:11 volumio3 volumio[3425]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 01:05:11 volumio3 sudo[4315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 01:04' Dec 26 01:05:11 volumio3 sudo[4315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"